[02:32:57.403] New invocation is queued and will start shortly
[02:32:58.478] Starting the invocation (attempt 1)
[02:32:58.513] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:32:58.513] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:32:58.513] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054036683495797920",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"365fdbb649d1e34da3989cb5d40ea454a676321f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054036683495797920",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/365fdbb649d1e34da3989cb5d40ea454a676321f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/365fdbb649d1e34da3989cb5d40ea454a676321f",
"gitiles_ref:refs/heads/master"
]
}
[02:32:59.646] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581561178811296",
"id": "8888606621324893360",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"365fdbb649d1e34da3989cb5d40ea454a676321f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581561179382112",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168537",
"builder:Linux Builder (dbg)",
"buildset:commit/git/365fdbb649d1e34da3989cb5d40ea454a676321f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/365fdbb649d1e34da3989cb5d40ea454a676321f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054036683495797920",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888606621324893360/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1581561179382266",
"url": "https://ci.chromium.org/b/8888606621324893360",
"utcnow_ts": "1581561179625101"
}
}
[02:32:59.646] Task URL: https://ci.chromium.org/b/8888606621324893360
[02:32:59.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:2:0) after 1m0s
[02:33:59.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:2:0)
[02:33:59.694] Timer tick, asking Buildbucket for the build status
[02:34:00.157] Build 8888606621324893360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:00.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:3:0) after 1m0s
[02:35:00.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:3:0)
[02:35:00.209] Timer tick, asking Buildbucket for the build status
[02:35:00.370] Build 8888606621324893360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:00.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:4:0) after 1m0s
[02:36:00.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:4:0)
[02:36:00.452] Timer tick, asking Buildbucket for the build status
[02:36:00.838] Build 8888606621324893360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:5:0) after 1m0s
[02:37:00.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:5:0)
[02:37:00.860] Timer tick, asking Buildbucket for the build status
[02:37:01.505] Build 8888606621324893360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:01.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:6:0) after 1m0s
[02:38:01.423] Received PubSub notification, asking Buildbucket for the build status
[02:38:01.619] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:02.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:6:0)
[02:38:02.475] Timer tick, asking Buildbucket for the build status
[02:38:02.579] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:02.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:8:0) after 1m0s
[02:39:02.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:8:0)
[02:39:02.784] Timer tick, asking Buildbucket for the build status
[02:39:02.956] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:9:0) after 1m0s
[02:40:03.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:9:0)
[02:40:03.153] Timer tick, asking Buildbucket for the build status
[02:40:03.552] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:03.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:10:0) after 1m0s
[02:41:03.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:10:0)
[02:41:03.650] Timer tick, asking Buildbucket for the build status
[02:41:04.057] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:04.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:11:0) after 1m0s
[02:42:04.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:11:0)
[02:42:04.088] Timer tick, asking Buildbucket for the build status
[02:42:04.667] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:04.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:12:0) after 1m0s
[02:43:04.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:12:0)
[02:43:04.693] Timer tick, asking Buildbucket for the build status
[02:43:05.591] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:05.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:13:0) after 1m0s
[02:44:05.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:13:0)
[02:44:05.745] Timer tick, asking Buildbucket for the build status
[02:44:05.908] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:05.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:14:0) after 1m0s
[02:45:06.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:14:0)
[02:45:06.094] Timer tick, asking Buildbucket for the build status
[02:45:06.299] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:06.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:15:0) after 1m0s
[02:46:06.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:15:0)
[02:46:06.324] Timer tick, asking Buildbucket for the build status
[02:46:06.513] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:06.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:16:0) after 1m0s
[02:47:06.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:16:0)
[02:47:06.567] Timer tick, asking Buildbucket for the build status
[02:47:06.779] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:06.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:17:0) after 1m0s
[02:48:06.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:17:0)
[02:48:06.856] Timer tick, asking Buildbucket for the build status
[02:48:07.007] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:07.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:18:0) after 1m0s
[02:49:07.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:18:0)
[02:49:07.651] Timer tick, asking Buildbucket for the build status
[02:49:08.463] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:08.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:19:0) after 1m0s
[02:50:08.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:19:0)
[02:50:08.557] Timer tick, asking Buildbucket for the build status
[02:50:08.856] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:08.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:20:0) after 1m0s
[02:51:09.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:20:0)
[02:51:09.007] Timer tick, asking Buildbucket for the build status
[02:51:09.348] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:09.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:21:0) after 1m0s
[02:52:09.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:21:0)
[02:52:09.654] Timer tick, asking Buildbucket for the build status
[02:52:09.865] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:09.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:22:0) after 1m0s
[02:53:10.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:22:0)
[02:53:10.020] Timer tick, asking Buildbucket for the build status
[02:53:10.225] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:23:0) after 1m0s
[02:54:10.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:23:0)
[02:54:10.246] Timer tick, asking Buildbucket for the build status
[02:54:10.860] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:10.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:24:0) after 1m0s
[02:55:10.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:24:0)
[02:55:10.949] Timer tick, asking Buildbucket for the build status
[02:55:11.305] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:11.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:25:0) after 1m0s
[02:56:11.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:25:0)
[02:56:11.504] Timer tick, asking Buildbucket for the build status
[02:56:11.889] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:26:0) after 1m0s
[02:57:11.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:26:0)
[02:57:11.890] Timer tick, asking Buildbucket for the build status
[02:57:12.794] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:12.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:27:0) after 1m0s
[02:58:12.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:27:0)
[02:58:12.851] Timer tick, asking Buildbucket for the build status
[02:58:13.372] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:13.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:28:0) after 1m0s
[02:59:13.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:28:0)
[02:59:13.401] Timer tick, asking Buildbucket for the build status
[02:59:14.145] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:14.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:29:0) after 1m0s
[03:00:14.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:29:0)
[03:00:14.155] Timer tick, asking Buildbucket for the build status
[03:00:14.601] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:14.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:30:0) after 1m0s
[03:01:14.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:30:0)
[03:01:14.683] Timer tick, asking Buildbucket for the build status
[03:01:15.627] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:15.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:31:0) after 1m0s
[03:02:15.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:31:0)
[03:02:15.723] Timer tick, asking Buildbucket for the build status
[03:02:16.031] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:16.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:32:0) after 1m0s
[03:03:16.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:32:0)
[03:03:16.065] Timer tick, asking Buildbucket for the build status
[03:03:16.355] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:16.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:33:0) after 1m0s
[03:04:16.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:33:0)
[03:04:16.369] Timer tick, asking Buildbucket for the build status
[03:04:16.578] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:16.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:34:0) after 1m0s
[03:05:16.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:34:0)
[03:05:16.661] Timer tick, asking Buildbucket for the build status
[03:05:16.956] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:16.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:35:0) after 1m0s
[03:06:17.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:35:0)
[03:06:17.079] Timer tick, asking Buildbucket for the build status
[03:06:17.226] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:17.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:36:0) after 1m0s
[03:07:17.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:36:0)
[03:07:17.262] Timer tick, asking Buildbucket for the build status
[03:07:18.195] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:18.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:37:0) after 1m0s
[03:08:18.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:37:0)
[03:08:18.280] Timer tick, asking Buildbucket for the build status
[03:08:18.573] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:18.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:38:0) after 1m0s
[03:09:18.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:38:0)
[03:09:18.637] Timer tick, asking Buildbucket for the build status
[03:09:18.792] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:18.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:39:0) after 1m0s
[03:10:18.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:39:0)
[03:10:18.880] Timer tick, asking Buildbucket for the build status
[03:10:19.058] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:19.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:40:0) after 1m0s
[03:11:19.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:40:0)
[03:11:19.068] Timer tick, asking Buildbucket for the build status
[03:11:19.228] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:19.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:41:0) after 1m0s
[03:12:19.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:41:0)
[03:12:19.512] Timer tick, asking Buildbucket for the build status
[03:12:19.937] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:19.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:42:0) after 1m0s
[03:13:20.047] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:42:0)
[03:13:20.047] Timer tick, asking Buildbucket for the build status
[03:13:21.008] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:21.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:43:0) after 1m0s
[03:14:20.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:43:0)
[03:14:20.986] Timer tick, asking Buildbucket for the build status
[03:14:22.309] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:22.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:44:0) after 1m0s
[03:15:22.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:44:0)
[03:15:22.352] Timer tick, asking Buildbucket for the build status
[03:15:22.952] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:22.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:45:0) after 1m0s
[03:16:23.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:45:0)
[03:16:23.003] Timer tick, asking Buildbucket for the build status
[03:16:23.225] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:23.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:46:0) after 1m0s
[03:17:23.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:46:0)
[03:17:23.290] Timer tick, asking Buildbucket for the build status
[03:17:23.524] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:23.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:47:0) after 1m0s
[03:18:23.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:47:0)
[03:18:23.639] Timer tick, asking Buildbucket for the build status
[03:18:23.889] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:23.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:48:0) after 1m0s
[03:19:23.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:48:0)
[03:19:23.940] Timer tick, asking Buildbucket for the build status
[03:19:24.270] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:24.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:49:0) after 1m0s
[03:20:24.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:49:0)
[03:20:24.358] Timer tick, asking Buildbucket for the build status
[03:20:24.805] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:24.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:50:0) after 1m0s
[03:21:25.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:50:0)
[03:21:25.021] Timer tick, asking Buildbucket for the build status
[03:21:26.681] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:26.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:51:0) after 1m0s
[03:22:26.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:51:0)
[03:22:26.936] Timer tick, asking Buildbucket for the build status
[03:22:27.124] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:27.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:52:0) after 1m0s
[03:23:27.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:52:0)
[03:23:27.327] Timer tick, asking Buildbucket for the build status
[03:23:27.769] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:27.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:53:0) after 1m0s
[03:24:28.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:53:0)
[03:24:28.132] Timer tick, asking Buildbucket for the build status
[03:24:28.397] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:28.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:54:0) after 1m0s
[03:25:28.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:54:0)
[03:25:28.407] Timer tick, asking Buildbucket for the build status
[03:25:28.610] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:28.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:55:0) after 1m0s
[03:26:28.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:55:0)
[03:26:28.996] Timer tick, asking Buildbucket for the build status
[03:26:29.426] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:29.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:56:0) after 1m0s
[03:27:29.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:56:0)
[03:27:29.741] Timer tick, asking Buildbucket for the build status
[03:27:30.244] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:30.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:57:0) after 1m0s
[03:28:30.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:57:0)
[03:28:30.700] Timer tick, asking Buildbucket for the build status
[03:28:30.887] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:30.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:58:0) after 1m0s
[03:29:31.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:58:0)
[03:29:31.355] Timer tick, asking Buildbucket for the build status
[03:29:31.688] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:31.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:59:0) after 1m0s
[03:30:32.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:59:0)
[03:30:32.154] Timer tick, asking Buildbucket for the build status
[03:30:32.336] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:32.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:60:0) after 1m0s
[03:31:32.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:60:0)
[03:31:32.463] Timer tick, asking Buildbucket for the build status
[03:31:32.648] Build 8888606621324893360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:32.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054036683495797920:61:0) after 1m0s
[03:32:11.923] Received PubSub notification, asking Buildbucket for the build status
[03:32:12.087] Build 8888606621324893360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:32:12.087] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581564678217901",
"created_by": "project:chromium",
"created_ts": "1581561178811296",
"id": "8888606621324893360",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"365fdbb649d1e34da3989cb5d40ea454a676321f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-xenial-us-east1-d-8-105-b5mi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168537, \"got_angle_revision\": \"74ab0bffb526308f9732c99c7efc59fd718ee89c\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"365fdbb649d1e34da3989cb5d40ea454a676321f\", \"got_revision_cp\": \"refs/heads/master@{#740936}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"ac4e1d236088f90e3bc97aa67002ffe56042e4f2\", \"got_v8_revision\": \"acf9c0def690e10a2dc454d0c0aab1ff73ec7cee\", \"got_v8_revision_cp\": \"refs/heads/8.2.67@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"365fdbb649d1e34da3989cb5d40ea454a676321f\", \"swarm_hashes_refs/heads/master(at){#740936}_without_patch\": {\"accessibility_unittests\": \"0a3d759c4dc0e720851935427216df347058d522\", \"angle_unittests\": \"efb5931af0200ba68c6a765b295b3e4421b57602\", \"app_shell_unittests\": \"59177c908fdf7e5d029483a3ae3085e8a80e065e\", \"aura_unittests\": \"2c65f90e02787432973adb5153d65789275ffd2d\", \"base_unittests\": \"cd70d6144e3238b8047ce9058a2938f01fb1271c\", \"base_util_unittests\": \"e17668784acbfa58b724b1c3f35cf3c631b506e7\", \"blink_common_unittests\": \"a5aa8f73fd70dd41014073fc0b6ae356448d3fb2\", \"blink_fuzzer_unittests\": \"37775214b32c9c54e572730fd12f532ae4f6592c\", \"blink_heap_unittests\": \"9092808f12e39f34e0ffb526126c4b4471f2d64e\", \"blink_platform_unittests\": \"808a390f03f5ac7e5d1cc84dec3e1ea740621ed5\", \"blink_python_tests\": \"7db47ce519d5dd01672a2972b9376873070378c1\", \"blink_unittests\": \"bbfa96b00683643842886ebd69b0ef8ea872707f\", \"blink_web_tests\": \"fb54e774fb42d836b9ac747e6bdb3b35c90fbc32\", \"boringssl_crypto_tests\": \"b87a055c98d697df135e48b0c23327df3468e283\", \"boringssl_ssl_tests\": \"98d469f6f38158412495b377357c0d4ac7d11c97\", \"browser_tests\": \"1ee68f45e0cc59b5b5f8646abd3cc24cb085df54\", \"cacheinvalidation_unittests\": \"63312ec567db8c629f743fe998eddfda58183bcb\", \"capture_unittests\": \"26e23b09c8b8f5785b6ea324e3598634bfc5cfb6\", \"cast_unittests\": \"5f0fe9180c39bcb55eddefc25423aeb3d9870487\", \"cc_unittests\": \"5199ce8eec52d3c799afe6da8c767d58f51f82c6\", \"chrome_app_unittests\": \"ca4fe66cb4c8a3c120cb585c5462d5f98b7c972e\", \"chromedriver_unittests\": \"066ec9b66bd8737bc4cdc2a0e9fa31d0c7aa81e5\", \"components_browsertests\": \"2cbed71dc3da9cac1364938d42bdc9593fec45d1\", \"components_unittests\": \"567188fcc66606cec9b5a7b201cf5e85d958a482\", \"compositor_unittests\": \"b3c046757c448039dfd3a30b308eb830adced4a7\", \"content_browsertests\": \"a51a220e30a83e098bf93a8013d9849bb3e74cc9\", \"content_shell_crash_test\": \"3f9756580418ddd570fd7d18b0ce81f90216dc72\", \"content_unittests\": \"2f03eed39308d78feb0ce174e4ddff814b7782a7\", \"crashpad_tests\": \"e12d70617f5414d5734e54cf7cf7e76a1c138961\", \"cronet_tests\": \"eb52b23d7c0946e0060642d6d9a5c19850c7ca0c\", \"cronet_unittests\": \"b81e2cb96c1f3b90d2adaecbd160af4be0bcbcfb\", \"crypto_unittests\": \"1924ae41beaaf91a8b7d97335bdd1bbe1751d70e\", \"dbus_unittests\": \"bab92fd98e2699ee4ede963aac0c36b8ee990add\", \"device_unittests\": \"40b877c284910fbdb9043b3524dc12c5e22d0b34\", \"display_unittests\": \"bccc39dd1355b9c803ddc4df018e27528c1b1c7d\", \"events_unittests\": \"583f98a17b424a0760947d67144c77924256eb58\", \"extensions_browsertests\": \"c1e52ef54ce9ef6b620fb30d0ca6021282a3df86\", \"extensions_unittests\": \"fa738a71f4643ab63f5f8856f669db7081c4d08b\", \"filesystem_service_unittests\": \"490a0d28c1b5e7de9d2b9d64644c21dce796ea31\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"92d563aa7c1cf9195758e56396e210c8ae2e7a7e\", \"gfx_unittests\": \"772d7d42178998bc43d223cd76335e126df919c2\", \"gin_unittests\": \"210e2288bf878bc7c25cf7874870ca57085e579f\", \"google_apis_unittests\": \"080da0fb1b5cf8580ea9051dd4eaf8ba2897e4d4\", \"gpu_unittests\": \"eeaa5c894c438da42c09d53015e3490d95bdb505\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"fab8b58188083ab0d7ea803f315934d46c509def\", \"headless_browsertests\": \"0f49a911173626886b1ef10452d45941d47a3421\", \"headless_unittests\": \"769ffef0b97e93c08d0cbffc287ade04f6772dd4\", \"interactive_ui_tests\": \"aa4e71eca9e040fed91843dc73a73ce1018a8748\", \"ipc_tests\": \"fdb899441b621aeb761451dca3c40a81118fd1e7\", \"jingle_unittests\": \"3d5e7f96062d31ec74902231b50c4a73a9973046\", \"latency_unittests\": \"fa1e96d87d10834047125b5e782318bd40ac0319\", \"libjingle_xmpp_unittests\": \"3470d7d77992d659dc720d8bce96b5fd0e7edb14\", \"media_blink_unittests\": \"1e6c4be97265cea3b258329443f59ff9da126a95\", \"media_service_unittests\": \"8237e81e66759272806a293cab755befd847fb5c\", \"media_unittests\": \"b931730fbd28f9cbe9b6e6ab0124ea60e4c0b175\", \"message_center_unittests\": \"31ba595339f029f2adf8ed16ea1c4300976efd64\", \"metrics_python_tests\": \"db2eab8fcaf4f3253119175d6009276de2655097\", \"midi_unittests\": \"f718caf7be432e2e91e5a26490321f8c9f4419a2\", \"mojo_core_unittests\": \"37d934fe119ab7e718daeca692ae912773e6bf51\", \"mojo_unittests\": \"2e2d91b1c46e22d7c012698597c58b6f07a3d1c1\", \"nacl_helper_nonsfi_unittests\": \"1b7c37e43aa25cebc010dee06effda0066402797\", \"nacl_loader_unittests\": \"c0b4655277216eaa668a9c7d53a0843edd2c98e6\", \"native_theme_unittests\": \"4961ac2553c17222cea929a4e0adeb147dc180ea\", \"net_unittests\": \"d68327a537d89cb0b2bd69588907f9070d217610\", \"pdf_unittests\": \"7dfc0d3a8706a93425091202d3c6fe25a5ae73ee\", \"perfetto_unittests\": \"0f93b3731a0153693ce3cb7e8c33dce71004662d\", \"ppapi_unittests\": \"fb8e318f3df35755fb8d625c82db0f4bde0a027e\", \"printing_unittests\": \"171d7473a9bea749168fc9df323ec54af0c90109\", \"remoting_unittests\": \"24ec8887e7aff71b4f6d8b7e2aeae34d0a18a91d\", \"sandbox_linux_unittests\": \"c954623bc4b652987c727c1e551e31948f025985\", \"service_manager_unittests\": \"23d7c0d9170e2db10848eac64be339cbcd414b12\", \"services_unittests\": \"499283ed5ebdb31ce821b2915eabda135c6f407f\", \"shell_dialogs_unittests\": \"293993aa993fae5f7211054f5659a7780aff5e7d\", \"skia_unittests\": \"0332c617a23c84f7b039a90f67c9ff5ec0f5e4e4\", \"snapshot_unittests\": \"3f0dce113af0f0f2b2b1189b7b5bcde8c9ed440b\", \"sql_unittests\": \"8b93b52ade2ec6ac586908db0d2057e13fc6e8ae\", \"storage_unittests\": \"d75fca3e6f583d5e2f21fc6f1f47390806a53a1b\", \"sync_integration_tests\": \"ddeac2fc1f3e86a2b4abbe56692e34f139b6160f\", \"telemetry_gpu_unittests\": \"99f7105dea51f1c389cc0881b2b2536141ea3cc6\", \"telemetry_perf_unittests\": \"c8c5a121e9e64d95046043a62a0bf4f8870bc798\", \"telemetry_unittests\": \"b312eb92594b774d3c64d6d5ce80be80cb070690\", \"traffic_annotation_auditor_unittests\": \"a88862794e0672dce1e4f5f9039cfdd5ce635821\", \"ui_base_unittests\": \"070fd2e956a34b414a3665eb697955fb03d44b9f\", \"ui_touch_selection_unittests\": \"ddf71a645d5a8d61cfd110293edc64002ffd05b3\", \"unit_tests\": \"86a5bd1821e3ae8ba22c46b200924af38bbbca1e\", \"url_unittests\": \"18df327d5be57610d0865e78bcac4f6d80710092\", \"views_perftests\": \"8c6e39df0b7c457ccc38609e3b4f0f87592e3ccb\", \"views_unittests\": \"ec697398a9bebc83b11917c6d666fbadc9e49bee\", \"viz_unittests\": \"4c9378a9168a651347236c0215b6d2a3fd46c9ab\", \"vr_common_unittests\": \"bcf62d8546d1b14a1dad965d7d387ee4491d701f\", \"vr_pixeltests\": \"c7817b9d4e3e919138581d46d30e011dd5c86a47\", \"webdriver_wpt_tests\": \"c578ac44ff1e4bdde65ad8ac89b1b5df06e1d4c8\", \"weblayer_browsertests\": \"a65410de2759cb9b0b9e50a850ba586708eb35a3\", \"wm_unittests\": \"3c7ac3791c8037965056e7a6a3e5d27b4db06181\", \"wtf_unittests\": \"eb9903a41bb7076287ee9ec00cd46744e8cc4fd9\", \"xr_browser_tests\": \"fef4946f8be9ff2903ad4cd190f5953bc0c71bbb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_51603c189459d949c9f9b05c6193f37cd30704e77931c7dc651854102fbe3f3d_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-105-b5mi\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581561433826944",
"status": "COMPLETED",
"status_changed_ts": "1581564731474997",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168537",
"builder:Linux Builder (dbg)",
"buildset:commit/git/365fdbb649d1e34da3989cb5d40ea454a676321f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/365fdbb649d1e34da3989cb5d40ea454a676321f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054036683495797920",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888606621324893360/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4a5535f647401c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581564731479649",
"url": "https://ci.chromium.org/b/8888606621324893360",
"utcnow_ts": "1581564732078813"
}
}
[03:32:12.087] Invocation finished in 59m14.729642971s with status SUCCEEDED