[04:28:56.195] New invocation is queued and will start shortly
[04:28:57.386] Starting the invocation (attempt 1)
[04:28:57.424] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:28:57.424] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:28:57.424] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085919518817944656",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"54b2f786c6a641c564fe39ea5ef0e49528a00a39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9085919518817944656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"gitiles_ref:refs/heads/master"
]
}
[04:28:58.568] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551155337507627",
"id": "8920489456776833296",
"lease_expiration_ts": "1551274138228769",
"lease_key": "66357839",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"54b2f786c6a641c564fe39ea5ef0e49528a00a39\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551155338373015",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5396",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085919518817944656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8920489456776833296/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4340e1a326b22410",
"user_agent:luci-scheduler"
],
"updated_ts": "1551155338373197",
"url": "https://ci.chromium.org/b/8920489456776833296",
"utcnow_ts": "1551155338557865"
}
}
[04:28:58.568] Task URL: https://ci.chromium.org/b/8920489456776833296
[04:28:58.568] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:2:0) after 1m0s
[04:29:00.108] Received PubSub notification, asking Buildbucket for the build status
[04:29:00.169] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:2:0)
[04:29:58.553] Timer tick, asking Buildbucket for the build status
[04:29:58.732] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.732] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:4:0) after 1m0s
[04:30:58.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:4:0)
[04:30:58.721] Timer tick, asking Buildbucket for the build status
[04:30:58.770] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.770] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:5:0) after 1m0s
[04:31:58.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:5:0)
[04:31:58.778] Timer tick, asking Buildbucket for the build status
[04:31:58.841] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:6:0) after 1m0s
[04:32:58.859] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:6:0)
[04:32:58.859] Timer tick, asking Buildbucket for the build status
[04:32:58.950] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:7:0) after 1m0s
[04:33:58.938] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:7:0)
[04:33:58.938] Timer tick, asking Buildbucket for the build status
[04:33:59.019] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.019] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:8:0) after 1m0s
[04:34:58.990] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:8:0)
[04:34:58.990] Timer tick, asking Buildbucket for the build status
[04:34:59.162] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:9:0) after 1m0s
[04:35:59.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:9:0)
[04:35:59.155] Timer tick, asking Buildbucket for the build status
[04:35:59.450] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.450] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:10:0) after 1m0s
[04:36:59.452] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:10:0)
[04:36:59.452] Timer tick, asking Buildbucket for the build status
[04:36:59.495] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.495] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:11:0) after 1m0s
[04:37:59.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:11:0)
[04:37:59.521] Timer tick, asking Buildbucket for the build status
[04:37:59.567] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:12:0) after 1m0s
[04:38:59.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:12:0)
[04:38:59.542] Timer tick, asking Buildbucket for the build status
[04:38:59.591] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.591] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:13:0) after 1m0s
[04:39:59.608] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:13:0)
[04:39:59.608] Timer tick, asking Buildbucket for the build status
[04:39:59.904] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:14:0) after 1m0s
[04:40:59.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:14:0)
[04:40:59.871] Timer tick, asking Buildbucket for the build status
[04:41:00.193] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:15:0) after 1m0s
[04:42:00.216] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:15:0)
[04:42:00.216] Timer tick, asking Buildbucket for the build status
[04:42:00.284] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:16:0) after 1m0s
[04:43:00.303] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:16:0)
[04:43:00.303] Timer tick, asking Buildbucket for the build status
[04:43:00.363] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.363] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:17:0) after 1m0s
[04:44:00.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:17:0)
[04:44:00.376] Timer tick, asking Buildbucket for the build status
[04:44:00.475] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:18:0) after 1m0s
[04:45:00.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:18:0)
[04:45:00.505] Timer tick, asking Buildbucket for the build status
[04:45:00.778] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:00.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:19:0) after 1m0s
[04:46:00.755] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:19:0)
[04:46:00.755] Timer tick, asking Buildbucket for the build status
[04:46:00.807] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:00.807] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:20:0) after 1m0s
[04:47:00.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:20:0)
[04:47:00.799] Timer tick, asking Buildbucket for the build status
[04:47:00.838] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:21:0) after 1m0s
[04:48:00.848] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:21:0)
[04:48:00.848] Timer tick, asking Buildbucket for the build status
[04:48:00.925] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:00.925] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:22:0) after 1m0s
[04:49:00.960] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:22:0)
[04:49:00.960] Timer tick, asking Buildbucket for the build status
[04:49:01.011] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:01.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:23:0) after 1m0s
[04:50:01.023] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:23:0)
[04:50:01.023] Timer tick, asking Buildbucket for the build status
[04:50:01.162] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:01.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:24:0) after 1m0s
[04:51:01.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:24:0)
[04:51:01.209] Timer tick, asking Buildbucket for the build status
[04:51:01.304] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:01.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:25:0) after 1m0s
[04:52:01.322] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:25:0)
[04:52:01.322] Timer tick, asking Buildbucket for the build status
[04:52:01.412] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:01.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:26:0) after 1m0s
[04:53:01.420] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:26:0)
[04:53:01.420] Timer tick, asking Buildbucket for the build status
[04:53:01.758] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:01.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:27:0) after 1m0s
[04:54:01.775] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:27:0)
[04:54:01.775] Timer tick, asking Buildbucket for the build status
[04:54:01.876] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:01.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:28:0) after 1m0s
[04:55:01.907] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:28:0)
[04:55:01.907] Timer tick, asking Buildbucket for the build status
[04:55:01.953] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:01.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:29:0) after 1m0s
[04:56:01.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:29:0)
[04:56:01.926] Timer tick, asking Buildbucket for the build status
[04:56:02.160] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:02.160] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:30:0) after 1m0s
[04:57:02.174] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:30:0)
[04:57:02.174] Timer tick, asking Buildbucket for the build status
[04:57:02.422] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:02.422] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:31:0) after 1m0s
[04:58:02.490] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:31:0)
[04:58:02.490] Timer tick, asking Buildbucket for the build status
[04:58:02.581] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:02.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:32:0) after 1m0s
[04:59:02.615] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:32:0)
[04:59:02.615] Timer tick, asking Buildbucket for the build status
[04:59:02.660] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:02.660] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:33:0) after 1m0s
[05:00:02.687] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:33:0)
[05:00:02.687] Timer tick, asking Buildbucket for the build status
[05:00:02.768] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:02.768] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:34:0) after 1m0s
[05:01:02.800] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:34:0)
[05:01:02.800] Timer tick, asking Buildbucket for the build status
[05:01:03.049] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:03.049] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:35:0) after 1m0s
[05:02:03.091] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:35:0)
[05:02:03.091] Timer tick, asking Buildbucket for the build status
[05:02:03.288] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:03.288] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:36:0) after 1m0s
[05:03:03.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:36:0)
[05:03:03.262] Timer tick, asking Buildbucket for the build status
[05:03:03.568] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:03.568] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:37:0) after 1m0s
[05:04:03.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:37:0)
[05:04:03.573] Timer tick, asking Buildbucket for the build status
[05:04:03.624] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:03.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:38:0) after 1m0s
[05:05:03.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:38:0)
[05:05:03.638] Timer tick, asking Buildbucket for the build status
[05:05:03.817] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:03.817] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:39:0) after 1m0s
[05:06:03.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:39:0)
[05:06:03.799] Timer tick, asking Buildbucket for the build status
[05:06:03.891] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:03.891] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:40:0) after 1m0s
[05:07:03.912] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:40:0)
[05:07:03.912] Timer tick, asking Buildbucket for the build status
[05:07:04.199] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:04.199] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:41:0) after 1m0s
[05:08:04.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:41:0)
[05:08:04.237] Timer tick, asking Buildbucket for the build status
[05:08:04.364] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:04.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:42:0) after 1m0s
[05:09:04.385] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:42:0)
[05:09:04.385] Timer tick, asking Buildbucket for the build status
[05:09:04.505] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:04.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:43:0) after 1m0s
[05:10:04.511] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:43:0)
[05:10:04.511] Timer tick, asking Buildbucket for the build status
[05:10:04.564] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:04.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:44:0) after 1m0s
[05:11:04.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:44:0)
[05:11:04.672] Timer tick, asking Buildbucket for the build status
[05:11:04.757] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:04.757] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:45:0) after 1m0s
[05:12:04.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:45:0)
[05:12:04.778] Timer tick, asking Buildbucket for the build status
[05:12:04.832] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:04.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:46:0) after 1m0s
[05:13:04.832] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:46:0)
[05:13:04.832] Timer tick, asking Buildbucket for the build status
[05:13:05.196] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:05.196] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:47:0) after 1m0s
[05:14:05.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:47:0)
[05:14:05.219] Timer tick, asking Buildbucket for the build status
[05:14:05.260] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:05.260] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:48:0) after 1m0s
[05:15:05.250] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:48:0)
[05:15:05.250] Timer tick, asking Buildbucket for the build status
[05:15:05.373] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:05.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:49:0) after 1m0s
[05:16:05.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:49:0)
[05:16:05.359] Timer tick, asking Buildbucket for the build status
[05:16:05.505] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:05.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:50:0) after 1m0s
[05:17:05.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:50:0)
[05:17:05.528] Timer tick, asking Buildbucket for the build status
[05:17:05.681] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:05.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:51:0) after 1m0s
[05:18:05.691] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:51:0)
[05:18:05.691] Timer tick, asking Buildbucket for the build status
[05:18:06.082] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:06.082] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:52:0) after 1m0s
[05:19:06.107] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:52:0)
[05:19:06.107] Timer tick, asking Buildbucket for the build status
[05:19:06.274] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:06.274] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:53:0) after 1m0s
[05:20:06.293] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:53:0)
[05:20:06.294] Timer tick, asking Buildbucket for the build status
[05:20:06.341] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:06.341] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:54:0) after 1m0s
[05:21:06.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:54:0)
[05:21:06.376] Timer tick, asking Buildbucket for the build status
[05:21:06.445] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:06.445] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:55:0) after 1m0s
[05:22:06.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:55:0)
[05:22:06.508] Timer tick, asking Buildbucket for the build status
[05:22:06.774] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:06.774] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:56:0) after 1m0s
[05:23:06.797] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:56:0)
[05:23:06.797] Timer tick, asking Buildbucket for the build status
[05:23:06.842] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:06.842] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:57:0) after 1m0s
[05:24:06.865] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:57:0)
[05:24:06.865] Timer tick, asking Buildbucket for the build status
[05:24:07.069] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:07.069] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:58:0) after 1m0s
[05:25:07.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:58:0)
[05:25:07.092] Timer tick, asking Buildbucket for the build status
[05:25:07.138] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:07.138] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:59:0) after 1m0s
[05:26:07.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:59:0)
[05:26:07.166] Timer tick, asking Buildbucket for the build status
[05:26:07.248] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:07.248] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:60:0) after 1m0s
[05:27:07.308] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:60:0)
[05:27:07.308] Timer tick, asking Buildbucket for the build status
[05:27:07.569] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:07.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:61:0) after 1m0s
[05:28:07.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:61:0)
[05:28:07.596] Timer tick, asking Buildbucket for the build status
[05:28:07.691] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:07.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:62:0) after 1m0s
[05:29:07.688] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:62:0)
[05:29:07.688] Timer tick, asking Buildbucket for the build status
[05:29:07.755] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:07.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:63:0) after 1m0s
[05:30:07.733] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:63:0)
[05:30:07.733] Timer tick, asking Buildbucket for the build status
[05:30:07.828] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:07.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:64:0) after 1m0s
[05:31:07.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:64:0)
[05:31:07.849] Timer tick, asking Buildbucket for the build status
[05:31:07.895] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:07.895] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:65:0) after 1m0s
[05:32:07.915] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:65:0)
[05:32:07.915] Timer tick, asking Buildbucket for the build status
[05:32:08.123] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:08.123] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:66:0) after 1m0s
[05:33:08.188] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:66:0)
[05:33:08.188] Timer tick, asking Buildbucket for the build status
[05:33:08.231] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:08.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:67:0) after 1m0s
[05:34:08.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:67:0)
[05:34:08.263] Timer tick, asking Buildbucket for the build status
[05:34:08.333] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:08.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:68:0) after 1m0s
[05:35:08.350] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:68:0)
[05:35:08.350] Timer tick, asking Buildbucket for the build status
[05:35:08.395] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:08.395] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:69:0) after 1m0s
[05:36:08.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:69:0)
[05:36:08.414] Timer tick, asking Buildbucket for the build status
[05:36:09.847] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:70:0) after 1m0s
[05:37:09.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:70:0)
[05:37:09.871] Timer tick, asking Buildbucket for the build status
[05:37:10.063] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:10.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:71:0) after 1m0s
[05:38:10.365] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:71:0)
[05:38:10.365] Timer tick, asking Buildbucket for the build status
[05:38:10.429] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:10.429] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:72:0) after 1m0s
[05:39:10.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:72:0)
[05:39:10.488] Timer tick, asking Buildbucket for the build status
[05:39:10.542] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:10.543] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:73:0) after 1m0s
[05:40:10.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:73:0)
[05:40:10.563] Timer tick, asking Buildbucket for the build status
[05:40:10.629] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:10.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:74:0) after 1m0s
[05:41:10.664] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:74:0)
[05:41:10.665] Timer tick, asking Buildbucket for the build status
[05:41:10.780] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:10.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:75:0) after 1m0s
[05:42:10.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:75:0)
[05:42:10.772] Timer tick, asking Buildbucket for the build status
[05:42:10.833] Build 8920489456776833296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:10.833] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085919518817944656:76:0) after 1m0s
[05:42:48.692] Received PubSub notification, asking Buildbucket for the build status
[05:42:48.735] Build 8920489456776833296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:42:48.735] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551159767779693",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551155337507627",
"id": "8920489456776833296",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"54b2f786c6a641c564fe39ea5ef0e49528a00a39\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-a-n2zs\"], \"server_version\": [\"4129-0985e08\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-a\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"d50a88f50782ba29076061b94c7b9d08a6c7e424\", \"got_revision\": \"54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"9b8d2b0bbfad88916b6cef1f060d9047b0dcf7e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26830}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#635394}\", \"branch\": \"refs/heads/master\", \"revision\": \"54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"3812fa949a496498ea6030360089216294a38680\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"0546b5380780a5ae12db34a9ef4fb310935ef35b\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551155337507627, \"tags\": [\"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5396\", \"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085919518817944656\", \"scheduler_job_id:chromium/Deterministic Linux (dbg)\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8920489456776833296\"}}, \"got_v8_revision\": \"a32d20ab20f5a0416bc1bfab66b142c30269f1c0\", \"got_v8_revision_cp\": \"refs/heads/7.4.221@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 5396, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Deterministic Linux (dbg)\", \"key\": \"builder\"}, {\"value\": \"commit/git/54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/54b2f786c6a641c564fe39ea5ef0e49528a00a39\", \"key\": \"buildset\"}, {\"value\": \"9085919518817944656\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Deterministic Linux (dbg)\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Deterministic Linux (dbg)\", \"bucket\": \"ci\"}, \"number\": 5396, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"aed94bdb6fa0b2abb2bed1ef8ed7b1a334781d31\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920489456776833296\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"54b2f786c6a641c564fe39ea5ef0e49528a00a39\"}}, \"createTime\": \"2019-02-26T04:28:57.507627Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-a-n2zs\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551155339653676",
"status": "COMPLETED",
"status_changed_ts": "1551159768308549",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5396",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54b2f786c6a641c564fe39ea5ef0e49528a00a39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085919518817944656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8920489456776833296/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4340e1a326b22410",
"user_agent:luci-scheduler"
],
"updated_ts": "1551159768310422",
"url": "https://ci.chromium.org/b/8920489456776833296",
"utcnow_ts": "1551159768722983"
}
}
[05:42:48.735] Invocation finished in 1h13m52.557877608s with status SUCCEEDED