[00:41:16.481] New invocation is queued and will start shortly
[00:41:17.691] Starting the invocation (attempt 1)
[00:41:17.725] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:41:17.726] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:41:17.726] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078142502955624720",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"efed9883346c536add8f9a430ac1e0f7f08d49fc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9078142502955624720",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"gitiles_ref:refs/heads/master"
]
}
[00:41:20.072] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558572078596962",
"id": "8912712440071923888",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"efed9883346c536add8f9a430ac1e0f7f08d49fc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558572079807552",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/7818",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078142502955624720",
"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/8912712440071923888/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558572079807707",
"url": "https://ci.chromium.org/b/8912712440071923888",
"utcnow_ts": "1558572080059169"
}
}
[00:41:20.072] Task URL: https://ci.chromium.org/b/8912712440071923888
[00:41:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:2:0) after 1m0s
[00:41:22.722] Received PubSub notification, asking Buildbucket for the build status
[00:41:22.901] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:20.116] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:2:0)
[00:42:20.116] Timer tick, asking Buildbucket for the build status
[00:42:22.265] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:22.265] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:4:0) after 1m0s
[00:43:22.386] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:4:0)
[00:43:22.386] Timer tick, asking Buildbucket for the build status
[00:43:22.627] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:22.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:5:0) after 1m0s
[00:44:22.651] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:5:0)
[00:44:22.651] Timer tick, asking Buildbucket for the build status
[00:44:25.950] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:25.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:6:0) after 1m0s
[00:45:25.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:6:0)
[00:45:25.970] Timer tick, asking Buildbucket for the build status
[00:45:26.253] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:26.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:7:0) after 1m0s
[00:46:26.273] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:7:0)
[00:46:26.273] Timer tick, asking Buildbucket for the build status
[00:46:26.393] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:26.393] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:8:0) after 1m0s
[00:47:26.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:8:0)
[00:47:26.459] Timer tick, asking Buildbucket for the build status
[00:47:26.698] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:26.698] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:9:0) after 1m0s
[00:48:26.743] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:9:0)
[00:48:26.743] Timer tick, asking Buildbucket for the build status
[00:48:27.404] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:27.404] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:10:0) after 1m0s
[00:49:27.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:10:0)
[00:49:27.436] Timer tick, asking Buildbucket for the build status
[00:49:29.977] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:29.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:11:0) after 1m0s
[00:50:30.156] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:11:0)
[00:50:30.156] Timer tick, asking Buildbucket for the build status
[00:50:30.402] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:12:0) after 1m0s
[00:51:30.527] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:12:0)
[00:51:30.527] Timer tick, asking Buildbucket for the build status
[00:51:30.827] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:30.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:13:0) after 1m0s
[00:52:31.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:13:0)
[00:52:31.276] Timer tick, asking Buildbucket for the build status
[00:52:31.392] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:31.392] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:14:0) after 1m0s
[00:53:31.540] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:14:0)
[00:53:31.540] Timer tick, asking Buildbucket for the build status
[00:53:31.683] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:31.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:15:0) after 1m0s
[00:54:31.663] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:15:0)
[00:54:31.663] Timer tick, asking Buildbucket for the build status
[00:54:31.810] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:31.810] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:16:0) after 1m0s
[00:55:31.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:16:0)
[00:55:31.931] Timer tick, asking Buildbucket for the build status
[00:55:33.424] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:33.424] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:17:0) after 1m0s
[00:56:33.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:17:0)
[00:56:33.529] Timer tick, asking Buildbucket for the build status
[00:56:36.295] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:36.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:18:0) after 1m0s
[00:57:36.306] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:18:0)
[00:57:36.306] Timer tick, asking Buildbucket for the build status
[00:57:37.310] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:37.310] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:19:0) after 1m0s
[00:58:37.331] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:19:0)
[00:58:37.331] Timer tick, asking Buildbucket for the build status
[00:58:37.851] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:37.851] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:20:0) after 1m0s
[00:59:37.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:20:0)
[00:59:37.919] Timer tick, asking Buildbucket for the build status
[00:59:38.295] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:38.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:21:0) after 1m0s
[01:00:38.300] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:21:0)
[01:00:38.300] Timer tick, asking Buildbucket for the build status
[01:00:41.909] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:41.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:22:0) after 1m0s
[01:01:42.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:22:0)
[01:01:42.027] Timer tick, asking Buildbucket for the build status
[01:01:42.930] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:42.930] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:23:0) after 1m0s
[01:02:43.250] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:23:0)
[01:02:43.250] Timer tick, asking Buildbucket for the build status
[01:02:44.496] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:44.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:24:0) after 1m0s
[01:03:44.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:24:0)
[01:03:44.557] Timer tick, asking Buildbucket for the build status
[01:03:44.723] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:44.723] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:25:0) after 1m0s
[01:04:44.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:25:0)
[01:04:44.799] Timer tick, asking Buildbucket for the build status
[01:04:47.181] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:47.181] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:26:0) after 1m0s
[01:05:47.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:26:0)
[01:05:47.262] Timer tick, asking Buildbucket for the build status
[01:05:48.418] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:48.418] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:27:0) after 1m0s
[01:06:48.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:27:0)
[01:06:48.436] Timer tick, asking Buildbucket for the build status
[01:06:48.553] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:28:0) after 1m0s
[01:07:48.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:28:0)
[01:07:48.573] Timer tick, asking Buildbucket for the build status
[01:07:48.673] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:48.673] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:29:0) after 1m0s
[01:08:48.780] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:29:0)
[01:08:48.780] Timer tick, asking Buildbucket for the build status
[01:08:50.859] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:50.859] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:30:0) after 1m0s
[01:09:50.918] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:30:0)
[01:09:50.918] Timer tick, asking Buildbucket for the build status
[01:09:51.034] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:51.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:31:0) after 1m0s
[01:10:51.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:31:0)
[01:10:51.065] Timer tick, asking Buildbucket for the build status
[01:10:51.466] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:32:0) after 1m0s
[01:11:51.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:32:0)
[01:11:51.461] Timer tick, asking Buildbucket for the build status
[01:11:51.603] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:51.603] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:33:0) after 1m0s
[01:12:51.653] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:33:0)
[01:12:51.653] Timer tick, asking Buildbucket for the build status
[01:12:51.996] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:51.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:34:0) after 1m0s
[01:13:52.029] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:34:0)
[01:13:52.029] Timer tick, asking Buildbucket for the build status
[01:13:52.394] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:52.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:35:0) after 1m0s
[01:14:52.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:35:0)
[01:14:52.489] Timer tick, asking Buildbucket for the build status
[01:14:53.090] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:53.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:36:0) after 1m0s
[01:15:53.156] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:36:0)
[01:15:53.156] Timer tick, asking Buildbucket for the build status
[01:15:53.251] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:53.251] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:37:0) after 1m0s
[01:16:53.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:37:0)
[01:16:53.316] Timer tick, asking Buildbucket for the build status
[01:16:53.422] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:53.422] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:38:0) after 1m0s
[01:17:53.444] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:38:0)
[01:17:53.444] Timer tick, asking Buildbucket for the build status
[01:17:53.613] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:53.613] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:39:0) after 1m0s
[01:18:53.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:39:0)
[01:18:53.642] Timer tick, asking Buildbucket for the build status
[01:18:54.947] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:54.947] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:40:0) after 1m0s
[01:19:54.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:40:0)
[01:19:54.970] Timer tick, asking Buildbucket for the build status
[01:19:55.552] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.552] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:41:0) after 1m0s
[01:20:55.608] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:41:0)
[01:20:55.608] Timer tick, asking Buildbucket for the build status
[01:20:55.759] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.759] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:42:0) after 1m0s
[01:21:55.922] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:42:0)
[01:21:55.922] Timer tick, asking Buildbucket for the build status
[01:21:56.617] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:56.617] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:43:0) after 1m0s
[01:22:56.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:43:0)
[01:22:56.638] Timer tick, asking Buildbucket for the build status
[01:22:58.673] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:58.673] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:44:0) after 1m0s
[01:23:58.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:44:0)
[01:23:58.681] Timer tick, asking Buildbucket for the build status
[01:23:59.122] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:45:0) after 1m0s
[01:24:59.158] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:45:0)
[01:24:59.158] Timer tick, asking Buildbucket for the build status
[01:24:59.799] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:59.799] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:46:0) after 1m0s
[01:25:59.827] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:46:0)
[01:25:59.827] Timer tick, asking Buildbucket for the build status
[01:26:00.018] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:00.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:47:0) after 1m0s
[01:27:00.042] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:47:0)
[01:27:00.042] Timer tick, asking Buildbucket for the build status
[01:27:01.173] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:01.173] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:48:0) after 1m0s
[01:28:01.242] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:48:0)
[01:28:01.242] Timer tick, asking Buildbucket for the build status
[01:28:01.901] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:01.901] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:49:0) after 1m0s
[01:29:01.943] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:49:0)
[01:29:01.943] Timer tick, asking Buildbucket for the build status
[01:29:02.908] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:02.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:50:0) after 1m0s
[01:30:02.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:50:0)
[01:30:02.945] Timer tick, asking Buildbucket for the build status
[01:30:05.643] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:05.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:51:0) after 1m0s
[01:31:05.971] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:51:0)
[01:31:05.971] Timer tick, asking Buildbucket for the build status
[01:31:06.495] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:06.495] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:52:0) after 1m0s
[01:32:06.518] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:52:0)
[01:32:06.518] Timer tick, asking Buildbucket for the build status
[01:32:06.643] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:06.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:53:0) after 1m0s
[01:33:06.671] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:53:0)
[01:33:06.671] Timer tick, asking Buildbucket for the build status
[01:33:07.690] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:07.690] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:54:0) after 1m0s
[01:34:07.742] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:54:0)
[01:34:07.742] Timer tick, asking Buildbucket for the build status
[01:34:11.004] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:11.004] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:55:0) after 1m0s
[01:35:11.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:55:0)
[01:35:11.121] Timer tick, asking Buildbucket for the build status
[01:35:11.429] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:11.429] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:56:0) after 1m0s
[01:36:11.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:56:0)
[01:36:11.493] Timer tick, asking Buildbucket for the build status
[01:36:11.685] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:11.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:57:0) after 1m0s
[01:37:11.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:57:0)
[01:37:11.684] Timer tick, asking Buildbucket for the build status
[01:37:12.057] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:12.057] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:58:0) after 1m0s
[01:38:12.099] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:58:0)
[01:38:12.099] Timer tick, asking Buildbucket for the build status
[01:38:13.447] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:13.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:59:0) after 1m0s
[01:39:13.516] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:59:0)
[01:39:13.516] Timer tick, asking Buildbucket for the build status
[01:39:15.391] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:15.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:60:0) after 1m0s
[01:40:15.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:60:0)
[01:40:15.421] Timer tick, asking Buildbucket for the build status
[01:40:15.870] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:15.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:61:0) after 1m0s
[01:41:16.042] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:61:0)
[01:41:16.042] Timer tick, asking Buildbucket for the build status
[01:41:16.251] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:16.251] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:62:0) after 1m0s
[01:42:16.310] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:62:0)
[01:42:16.310] Timer tick, asking Buildbucket for the build status
[01:42:16.683] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:16.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:63:0) after 1m0s
[01:43:16.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:63:0)
[01:43:16.670] Timer tick, asking Buildbucket for the build status
[01:43:17.174] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:17.175] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:64:0) after 1m0s
[01:44:17.227] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:64:0)
[01:44:17.227] Timer tick, asking Buildbucket for the build status
[01:44:17.387] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:17.387] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:65:0) after 1m0s
[01:45:17.462] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:65:0)
[01:45:17.462] Timer tick, asking Buildbucket for the build status
[01:45:19.106] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:19.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:66:0) after 1m0s
[01:46:19.251] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:66:0)
[01:46:19.251] Timer tick, asking Buildbucket for the build status
[01:46:22.035] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:22.035] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:67:0) after 1m0s
[01:47:22.074] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:67:0)
[01:47:22.074] Timer tick, asking Buildbucket for the build status
[01:47:22.670] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:22.670] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:68:0) after 1m0s
[01:48:22.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:68:0)
[01:48:22.747] Timer tick, asking Buildbucket for the build status
[01:48:23.729] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:23.729] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:69:0) after 1m0s
[01:49:23.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:69:0)
[01:49:23.764] Timer tick, asking Buildbucket for the build status
[01:49:23.896] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:23.896] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:70:0) after 1m0s
[01:50:24.265] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:70:0)
[01:50:24.265] Timer tick, asking Buildbucket for the build status
[01:50:25.527] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:71:0) after 1m0s
[01:51:25.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:71:0)
[01:51:25.655] Timer tick, asking Buildbucket for the build status
[01:51:25.933] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:25.933] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:72:0) after 1m0s
[01:52:26.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:72:0)
[01:52:26.041] Timer tick, asking Buildbucket for the build status
[01:52:26.185] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:26.185] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:73:0) after 1m0s
[01:53:26.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:73:0)
[01:53:26.277] Timer tick, asking Buildbucket for the build status
[01:53:26.428] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:26.428] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:74:0) after 1m0s
[01:54:26.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:74:0)
[01:54:26.758] Timer tick, asking Buildbucket for the build status
[01:54:27.197] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:27.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:75:0) after 1m0s
[01:55:27.407] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:75:0)
[01:55:27.407] Timer tick, asking Buildbucket for the build status
[01:55:29.115] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:29.115] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:76:0) after 1m0s
[01:56:29.259] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:76:0)
[01:56:29.259] Timer tick, asking Buildbucket for the build status
[01:56:29.758] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:77:0) after 1m0s
[01:57:29.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:77:0)
[01:57:29.905] Timer tick, asking Buildbucket for the build status
[01:57:30.259] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:30.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:78:0) after 1m0s
[01:58:30.346] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:78:0)
[01:58:30.346] Timer tick, asking Buildbucket for the build status
[01:58:30.488] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:30.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:79:0) after 1m0s
[01:59:30.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:79:0)
[01:59:30.552] Timer tick, asking Buildbucket for the build status
[01:59:31.134] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:31.134] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:80:0) after 1m0s
[02:00:31.208] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:80:0)
[02:00:31.208] Timer tick, asking Buildbucket for the build status
[02:00:32.812] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:32.812] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:81:0) after 1m0s
[02:01:32.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:81:0)
[02:01:32.838] Timer tick, asking Buildbucket for the build status
[02:01:33.054] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:33.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:82:0) after 1m0s
[02:02:33.078] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:82:0)
[02:02:33.078] Timer tick, asking Buildbucket for the build status
[02:02:33.739] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:33.739] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:83:0) after 1m0s
[02:03:33.740] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:83:0)
[02:03:33.740] Timer tick, asking Buildbucket for the build status
[02:03:34.120] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:34.120] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:84:0) after 1m0s
[02:04:34.159] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:84:0)
[02:04:34.159] Timer tick, asking Buildbucket for the build status
[02:04:34.324] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:34.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:85:0) after 1m0s
[02:05:34.392] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:85:0)
[02:05:34.392] Timer tick, asking Buildbucket for the build status
[02:05:35.900] Build 8912712440071923888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:35.900] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9078142502955624720:86:0) after 1m0s
[02:06:03.030] Received PubSub notification, asking Buildbucket for the build status
[02:06:04.164] Build 8912712440071923888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:06:04.164] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558577160056621",
"created_by": "project:chromium",
"created_ts": "1558572078596962",
"id": "8912712440071923888",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"efed9883346c536add8f9a430ac1e0f7f08d49fc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-trusty-32-12-7dua\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 7818, \"got_angle_revision\": \"5093ba67643c6c66d7efa22859984ee86b7b9729\", \"got_dawn_revision\": \"1c85976abe4e059684375915f955fc36049c8380\", \"got_nacl_revision\": \"4744a43b6fc4aaa5230541efad113b3cd86ea0f8\", \"got_revision\": \"efed9883346c536add8f9a430ac1e0f7f08d49fc\", \"got_revision_cp\": \"refs/heads/master@{#662451}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"d49c2a067615abb915b5fd9017d99363ce431ebf\", \"got_v8_revision_cp\": \"refs/heads/7.6.260@{#1}\", \"got_webrtc_revision\": \"58c71db1b344295a059ae72d9fe247b7c1cd1047\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28027}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"efed9883346c536add8f9a430ac1e0f7f08d49fc\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-trusty-32-12-7dua\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558572081691872",
"status": "COMPLETED",
"status_changed_ts": "1558577161785079",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/7818",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/efed9883346c536add8f9a430ac1e0f7f08d49fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078142502955624720",
"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/8912712440071923888/+/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:44faf425878ec110",
"user_agent:luci-scheduler"
],
"updated_ts": "1558577161878415",
"url": "https://ci.chromium.org/b/8912712440071923888",
"utcnow_ts": "1558577164123800"
}
}
[02:06:04.164] Invocation finished in 1h24m47.701066235s with status SUCCEEDED