[09:38:02.269] New invocation is queued and will start shortly
[09:38:03.349] Starting the invocation (attempt 1)
[09:38:03.382] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:38:03.382] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:38:03.382] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083906938587487408",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"87fc709d44fb7eb01351fdd6c094bdada417481a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9083906938587487408",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/87fc709d44fb7eb01351fdd6c094bdada417481a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87fc709d44fb7eb01351fdd6c094bdada417481a",
"gitiles_ref:refs/heads/master"
]
}
[09:38:04.603] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553074683574116",
"id": "8918476876555373008",
"lease_expiration_ts": "1553193484226494",
"lease_key": "911580399",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"87fc709d44fb7eb01351fdd6c094bdada417481a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553074684394390",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5771",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/87fc709d44fb7eb01351fdd6c094bdada417481a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87fc709d44fb7eb01351fdd6c094bdada417481a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083906938587487408",
"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/8918476876555373008/+/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:43b3488855830010",
"user_agent:luci-scheduler"
],
"updated_ts": "1553074684394514",
"url": "https://ci.chromium.org/b/8918476876555373008",
"utcnow_ts": "1553074684567313"
}
}
[09:38:04.603] Task URL: https://ci.chromium.org/b/8918476876555373008
[09:38:04.603] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:2:0) after 1m0s
[09:38:05.436] Received PubSub notification, asking Buildbucket for the build status
[09:38:05.601] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:04.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:2:0)
[09:39:04.597] Timer tick, asking Buildbucket for the build status
[09:39:04.751] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:04.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:4:0) after 1m0s
[09:40:04.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:4:0)
[09:40:04.770] Timer tick, asking Buildbucket for the build status
[09:40:04.887] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:04.887] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:5:0) after 1m0s
[09:41:04.907] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:5:0)
[09:41:04.907] Timer tick, asking Buildbucket for the build status
[09:41:07.645] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:07.645] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:6:0) after 1m0s
[09:42:07.651] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:6:0)
[09:42:07.651] Timer tick, asking Buildbucket for the build status
[09:42:09.255] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:09.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:7:0) after 1m0s
[09:43:09.241] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:7:0)
[09:43:09.241] Timer tick, asking Buildbucket for the build status
[09:43:09.589] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:09.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:8:0) after 1m0s
[09:44:09.628] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:8:0)
[09:44:09.628] Timer tick, asking Buildbucket for the build status
[09:44:09.771] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:09.771] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:9:0) after 1m0s
[09:45:09.795] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:9:0)
[09:45:09.795] Timer tick, asking Buildbucket for the build status
[09:45:09.910] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:09.910] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:10:0) after 1m0s
[09:46:09.932] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:10:0)
[09:46:09.932] Timer tick, asking Buildbucket for the build status
[09:46:10.029] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:10.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:11:0) after 1m0s
[09:47:10.046] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:11:0)
[09:47:10.046] Timer tick, asking Buildbucket for the build status
[09:47:10.266] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:10.266] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:12:0) after 1m0s
[09:48:10.241] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:12:0)
[09:48:10.241] Timer tick, asking Buildbucket for the build status
[09:48:10.464] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:10.464] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:13:0) after 1m0s
[09:49:10.481] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:13:0)
[09:49:10.481] Timer tick, asking Buildbucket for the build status
[09:49:10.563] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:10.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:14:0) after 1m0s
[09:50:10.541] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:14:0)
[09:50:10.541] Timer tick, asking Buildbucket for the build status
[09:50:10.769] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:10.769] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:15:0) after 1m0s
[09:51:10.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:15:0)
[09:51:10.764] Timer tick, asking Buildbucket for the build status
[09:51:11.028] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:11.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:16:0) after 1m0s
[09:52:11.052] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:16:0)
[09:52:11.052] Timer tick, asking Buildbucket for the build status
[09:52:11.147] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:11.147] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:17:0) after 1m0s
[09:53:11.173] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:17:0)
[09:53:11.173] Timer tick, asking Buildbucket for the build status
[09:53:11.482] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:11.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:18:0) after 1m0s
[09:54:11.494] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:18:0)
[09:54:11.494] Timer tick, asking Buildbucket for the build status
[09:54:11.717] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:11.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:19:0) after 1m0s
[09:55:11.740] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:19:0)
[09:55:11.740] Timer tick, asking Buildbucket for the build status
[09:55:12.562] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:12.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:20:0) after 1m0s
[09:56:12.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:20:0)
[09:56:12.596] Timer tick, asking Buildbucket for the build status
[09:56:12.925] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:12.925] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:21:0) after 1m0s
[09:57:12.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:21:0)
[09:57:12.945] Timer tick, asking Buildbucket for the build status
[09:57:13.873] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:13.873] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:22:0) after 1m0s
[09:58:13.895] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:22:0)
[09:58:13.895] Timer tick, asking Buildbucket for the build status
[09:58:14.373] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:14.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:23:0) after 1m0s
[09:59:14.378] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:23:0)
[09:59:14.378] Timer tick, asking Buildbucket for the build status
[09:59:14.483] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:14.483] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:24:0) after 1m0s
[10:00:14.494] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:24:0)
[10:00:14.494] Timer tick, asking Buildbucket for the build status
[10:00:14.781] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:14.781] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:25:0) after 1m0s
[10:01:14.771] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:25:0)
[10:01:14.771] Timer tick, asking Buildbucket for the build status
[10:01:14.941] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:14.941] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:26:0) after 1m0s
[10:02:14.924] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:26:0)
[10:02:14.924] Timer tick, asking Buildbucket for the build status
[10:02:15.075] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:15.075] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:27:0) after 1m0s
[10:03:15.075] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:27:0)
[10:03:15.075] Timer tick, asking Buildbucket for the build status
[10:03:15.270] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:15.270] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:28:0) after 1m0s
[10:04:15.261] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:28:0)
[10:04:15.261] Timer tick, asking Buildbucket for the build status
[10:04:16.085] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:16.085] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:29:0) after 1m0s
[10:05:16.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:29:0)
[10:05:16.098] Timer tick, asking Buildbucket for the build status
[10:05:16.569] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:16.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:30:0) after 1m0s
[10:06:16.589] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:30:0)
[10:06:16.589] Timer tick, asking Buildbucket for the build status
[10:06:16.696] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:31:0) after 1m0s
[10:07:16.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:31:0)
[10:07:16.672] Timer tick, asking Buildbucket for the build status
[10:07:17.219] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:17.219] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:32:0) after 1m0s
[10:08:17.228] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:32:0)
[10:08:17.228] Timer tick, asking Buildbucket for the build status
[10:08:17.667] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:17.667] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:33:0) after 1m0s
[10:09:17.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:33:0)
[10:09:17.684] Timer tick, asking Buildbucket for the build status
[10:09:17.786] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:17.786] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:34:0) after 1m0s
[10:10:17.812] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:34:0)
[10:10:17.812] Timer tick, asking Buildbucket for the build status
[10:10:18.058] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:18.058] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:35:0) after 1m0s
[10:11:18.079] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:35:0)
[10:11:18.079] Timer tick, asking Buildbucket for the build status
[10:11:18.261] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:18.261] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:36:0) after 1m0s
[10:12:18.236] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:36:0)
[10:12:18.236] Timer tick, asking Buildbucket for the build status
[10:12:18.692] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:18.692] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:37:0) after 1m0s
[10:13:18.710] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:37:0)
[10:13:18.710] Timer tick, asking Buildbucket for the build status
[10:13:18.981] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:18.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:38:0) after 1m0s
[10:14:18.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:38:0)
[10:14:18.955] Timer tick, asking Buildbucket for the build status
[10:14:19.558] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:19.558] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:39:0) after 1m0s
[10:15:19.540] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:39:0)
[10:15:19.540] Timer tick, asking Buildbucket for the build status
[10:15:19.764] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:19.764] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:40:0) after 1m0s
[10:16:19.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:40:0)
[10:16:19.783] Timer tick, asking Buildbucket for the build status
[10:16:20.467] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:41:0) after 1m0s
[10:17:20.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:41:0)
[10:17:20.514] Timer tick, asking Buildbucket for the build status
[10:17:20.635] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:20.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:42:0) after 1m0s
[10:18:20.651] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:42:0)
[10:18:20.651] Timer tick, asking Buildbucket for the build status
[10:18:20.894] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:20.894] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:43:0) after 1m0s
[10:19:20.915] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:43:0)
[10:19:20.915] Timer tick, asking Buildbucket for the build status
[10:19:21.032] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:21.032] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:44:0) after 1m0s
[10:20:21.068] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:44:0)
[10:20:21.068] Timer tick, asking Buildbucket for the build status
[10:20:21.214] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:21.214] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:45:0) after 1m0s
[10:21:21.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:45:0)
[10:21:21.209] Timer tick, asking Buildbucket for the build status
[10:21:21.310] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:21.310] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:46:0) after 1m0s
[10:22:21.329] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:46:0)
[10:22:21.329] Timer tick, asking Buildbucket for the build status
[10:22:21.420] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:21.420] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:47:0) after 1m0s
[10:23:21.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:47:0)
[10:23:21.424] Timer tick, asking Buildbucket for the build status
[10:23:21.588] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:21.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:48:0) after 1m0s
[10:24:21.619] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:48:0)
[10:24:21.619] Timer tick, asking Buildbucket for the build status
[10:24:21.717] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:21.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:49:0) after 1m0s
[10:25:21.705] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:49:0)
[10:25:21.705] Timer tick, asking Buildbucket for the build status
[10:25:21.841] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:21.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:50:0) after 1m0s
[10:26:21.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:50:0)
[10:26:21.887] Timer tick, asking Buildbucket for the build status
[10:26:21.976] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:21.976] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:51:0) after 1m0s
[10:27:21.961] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:51:0)
[10:27:21.961] Timer tick, asking Buildbucket for the build status
[10:27:22.782] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:22.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:52:0) after 1m0s
[10:28:22.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:52:0)
[10:28:22.764] Timer tick, asking Buildbucket for the build status
[10:28:22.881] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:22.881] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:53:0) after 1m0s
[10:29:22.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:53:0)
[10:29:22.901] Timer tick, asking Buildbucket for the build status
[10:29:23.007] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:23.007] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:54:0) after 1m0s
[10:30:22.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:54:0)
[10:30:22.985] Timer tick, asking Buildbucket for the build status
[10:30:23.076] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:23.076] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:55:0) after 1m0s
[10:31:23.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:55:0)
[10:31:23.069] Timer tick, asking Buildbucket for the build status
[10:31:24.077] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:24.077] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:56:0) after 1m0s
[10:32:24.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:56:0)
[10:32:24.105] Timer tick, asking Buildbucket for the build status
[10:32:24.981] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:24.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:57:0) after 1m0s
[10:33:24.996] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:57:0)
[10:33:24.996] Timer tick, asking Buildbucket for the build status
[10:33:25.116] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:25.116] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:58:0) after 1m0s
[10:34:25.142] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:58:0)
[10:34:25.142] Timer tick, asking Buildbucket for the build status
[10:34:25.581] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:25.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:59:0) after 1m0s
[10:35:25.605] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:59:0)
[10:35:25.605] Timer tick, asking Buildbucket for the build status
[10:35:25.718] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:25.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:60:0) after 1m0s
[10:36:25.695] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:60:0)
[10:36:25.695] Timer tick, asking Buildbucket for the build status
[10:36:25.787] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:25.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:61:0) after 1m0s
[10:37:25.794] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:61:0)
[10:37:25.794] Timer tick, asking Buildbucket for the build status
[10:37:26.189] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:26.189] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:62:0) after 1m0s
[10:38:26.177] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:62:0)
[10:38:26.177] Timer tick, asking Buildbucket for the build status
[10:38:26.325] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:26.325] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:63:0) after 1m0s
[10:39:26.349] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:63:0)
[10:39:26.349] Timer tick, asking Buildbucket for the build status
[10:39:26.499] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:26.499] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:64:0) after 1m0s
[10:40:26.511] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:64:0)
[10:40:26.511] Timer tick, asking Buildbucket for the build status
[10:40:26.891] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:26.891] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:65:0) after 1m0s
[10:41:26.912] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:65:0)
[10:41:26.912] Timer tick, asking Buildbucket for the build status
[10:41:27.020] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:27.020] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:66:0) after 1m0s
[10:42:27.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:66:0)
[10:42:27.043] Timer tick, asking Buildbucket for the build status
[10:42:27.341] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:27.341] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:67:0) after 1m0s
[10:43:27.369] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:67:0)
[10:43:27.369] Timer tick, asking Buildbucket for the build status
[10:43:27.505] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:27.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:68:0) after 1m0s
[10:44:27.516] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:68:0)
[10:44:27.516] Timer tick, asking Buildbucket for the build status
[10:44:27.619] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:27.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:69:0) after 1m0s
[10:45:27.631] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:69:0)
[10:45:27.631] Timer tick, asking Buildbucket for the build status
[10:45:27.794] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:27.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:70:0) after 1m0s
[10:46:27.767] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:70:0)
[10:46:27.767] Timer tick, asking Buildbucket for the build status
[10:46:28.500] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:71:0) after 1m0s
[10:47:28.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:71:0)
[10:47:28.524] Timer tick, asking Buildbucket for the build status
[10:47:28.693] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:28.693] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:72:0) after 1m0s
[10:48:28.674] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:72:0)
[10:48:28.674] Timer tick, asking Buildbucket for the build status
[10:48:29.293] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:29.293] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:73:0) after 1m0s
[10:49:29.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:73:0)
[10:49:29.316] Timer tick, asking Buildbucket for the build status
[10:49:30.187] Build 8918476876555373008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:30.187] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083906938587487408:74:0) after 1m0s
[10:49:55.119] Received PubSub notification, asking Buildbucket for the build status
[10:49:55.210] Build 8918476876555373008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:49:55.210] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553078994386186",
"created_by": "project:chromium",
"created_ts": "1553074683574116",
"id": "8918476876555373008",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"87fc709d44fb7eb01351fdd6c094bdada417481a\"}}",
"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-s6lc\"], \"server_version\": [\"4211-90dafba\"], \"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\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_revision\": \"87fc709d44fb7eb01351fdd6c094bdada417481a\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"54e4d47db4910ebd1ffce0247b60d4e6f984774f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27160}\", \"$build/goma\": {\"jobs\": 500.0}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#642443}\", \"branch\": \"refs/heads/master\", \"revision\": \"87fc709d44fb7eb01351fdd6c094bdada417481a\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"6ce1be5a5a53eb0213075dd7591a7dd4a3708614\", \"got_v8_revision\": \"93306f1d7b2c10824e1e8876e5b8a3ab37c42b96\", \"got_v8_revision_cp\": \"refs/heads/7.5.72@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 5771, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-a-s6lc\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553074684741218",
"status": "COMPLETED",
"status_changed_ts": "1553078994941803",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5771",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/87fc709d44fb7eb01351fdd6c094bdada417481a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87fc709d44fb7eb01351fdd6c094bdada417481a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083906938587487408",
"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/8918476876555373008/+/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:43b3488855830010",
"user_agent:luci-scheduler"
],
"updated_ts": "1553078994944759",
"url": "https://ci.chromium.org/b/8918476876555373008",
"utcnow_ts": "1553078995202444"
}
}
[10:49:55.210] Invocation finished in 1h11m52.958412053s with status SUCCEEDED