[00:19:06.556] New invocation is queued and will start shortly
[00:19:07.667] Starting the invocation (attempt 1)
[00:19:07.709] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:19:07.711] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:19:07.711] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021611390445889360",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021611390445889360",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"gitiles_ref:refs/heads/master"
]
}
[00:19:08.836] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612484347798353",
"id": "8856181328482052016",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612484348446630",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22484",
"builder:linux-chromeos-dbg",
"buildset:commit/git/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021611390445889360",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856181328482052016/+/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": "1612484348446746",
"url": "https://ci.chromium.org/b/8856181328482052016",
"utcnow_ts": "1612484348819436"
}
}
[00:19:08.836] Task URL: https://ci.chromium.org/b/8856181328482052016
[00:19:08.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:2:0) after 1m0s
[00:20:08.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:2:0)
[00:20:08.884] Timer tick, asking Buildbucket for the build status
[00:20:09.049] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:09.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:3:0) after 1m0s
[00:21:09.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:3:0)
[00:21:09.105] Timer tick, asking Buildbucket for the build status
[00:21:09.196] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:09.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:4:0) after 1m0s
[00:22:09.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:4:0)
[00:22:09.173] Timer tick, asking Buildbucket for the build status
[00:22:09.268] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:09.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:5:0) after 1m0s
[00:23:09.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:5:0)
[00:23:09.308] Timer tick, asking Buildbucket for the build status
[00:23:09.452] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:09.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:6:0) after 1m0s
[00:24:10.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:6:0)
[00:24:10.119] Timer tick, asking Buildbucket for the build status
[00:24:10.240] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:10.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:7:0) after 1m0s
[00:25:10.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:7:0)
[00:25:10.658] Timer tick, asking Buildbucket for the build status
[00:25:10.865] Build 8856181328482052016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:10.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:8:0) after 1m0s
[00:25:17.544] Received PubSub notification, asking Buildbucket for the build status
[00:25:17.823] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:11.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:8:0)
[00:26:11.001] Timer tick, asking Buildbucket for the build status
[00:26:11.207] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:11.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:10:0) after 1m0s
[00:27:11.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:10:0)
[00:27:11.338] Timer tick, asking Buildbucket for the build status
[00:27:11.412] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:11:0) after 1m0s
[00:28:11.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:11:0)
[00:28:11.532] Timer tick, asking Buildbucket for the build status
[00:28:11.586] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:11.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:12:0) after 1m0s
[00:29:11.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:12:0)
[00:29:11.817] Timer tick, asking Buildbucket for the build status
[00:29:11.964] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:11.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:13:0) after 1m0s
[00:30:12.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:13:0)
[00:30:12.119] Timer tick, asking Buildbucket for the build status
[00:30:12.289] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:12.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:14:0) after 1m0s
[00:31:12.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:14:0)
[00:31:12.437] Timer tick, asking Buildbucket for the build status
[00:31:12.510] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:12.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:15:0) after 1m0s
[00:32:12.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:15:0)
[00:32:12.637] Timer tick, asking Buildbucket for the build status
[00:32:12.707] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:12.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:16:0) after 1m0s
[00:33:12.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:16:0)
[00:33:12.939] Timer tick, asking Buildbucket for the build status
[00:33:13.107] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:13.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:17:0) after 1m0s
[00:34:13.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:17:0)
[00:34:13.299] Timer tick, asking Buildbucket for the build status
[00:34:13.796] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:13.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:18:0) after 1m0s
[00:35:13.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:18:0)
[00:35:13.798] Timer tick, asking Buildbucket for the build status
[00:35:13.879] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:13.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:19:0) after 1m0s
[00:36:13.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:19:0)
[00:36:13.931] Timer tick, asking Buildbucket for the build status
[00:36:14.019] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:14.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:20:0) after 1m0s
[00:37:14.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:20:0)
[00:37:14.082] Timer tick, asking Buildbucket for the build status
[00:37:14.138] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:14.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:21:0) after 1m0s
[00:38:14.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:21:0)
[00:38:14.370] Timer tick, asking Buildbucket for the build status
[00:38:14.434] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:22:0) after 1m0s
[00:39:14.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:22:0)
[00:39:14.580] Timer tick, asking Buildbucket for the build status
[00:39:14.687] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:14.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:23:0) after 1m0s
[00:40:14.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:23:0)
[00:40:14.981] Timer tick, asking Buildbucket for the build status
[00:40:15.214] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:15.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:24:0) after 1m0s
[00:41:15.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:24:0)
[00:41:15.297] Timer tick, asking Buildbucket for the build status
[00:41:15.502] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:15.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:25:0) after 1m0s
[00:42:15.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:25:0)
[00:42:15.609] Timer tick, asking Buildbucket for the build status
[00:42:16.359] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:16.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:26:0) after 1m0s
[00:43:16.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:26:0)
[00:43:16.421] Timer tick, asking Buildbucket for the build status
[00:43:16.535] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:16.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:27:0) after 1m0s
[00:44:16.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:27:0)
[00:44:16.701] Timer tick, asking Buildbucket for the build status
[00:44:17.001] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:17.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:28:0) after 1m0s
[00:45:17.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:28:0)
[00:45:17.120] Timer tick, asking Buildbucket for the build status
[00:45:17.215] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:17.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:29:0) after 1m0s
[00:46:17.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:29:0)
[00:46:17.441] Timer tick, asking Buildbucket for the build status
[00:46:17.506] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:17.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:30:0) after 1m0s
[00:47:17.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:30:0)
[00:47:17.583] Timer tick, asking Buildbucket for the build status
[00:47:17.915] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:17.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:31:0) after 1m0s
[00:48:17.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:31:0)
[00:48:17.932] Timer tick, asking Buildbucket for the build status
[00:48:18.099] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:18.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:32:0) after 1m0s
[00:49:18.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:32:0)
[00:49:18.225] Timer tick, asking Buildbucket for the build status
[00:49:18.386] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:18.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:33:0) after 1m0s
[00:50:18.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:33:0)
[00:50:18.428] Timer tick, asking Buildbucket for the build status
[00:50:18.484] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:18.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:34:0) after 1m0s
[00:51:18.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:34:0)
[00:51:18.637] Timer tick, asking Buildbucket for the build status
[00:51:18.707] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:35:0) after 1m0s
[00:52:18.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:35:0)
[00:52:18.746] Timer tick, asking Buildbucket for the build status
[00:52:18.804] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:18.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:36:0) after 1m0s
[00:53:18.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:36:0)
[00:53:18.977] Timer tick, asking Buildbucket for the build status
[00:53:19.075] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:19.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:37:0) after 1m0s
[00:54:19.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:37:0)
[00:54:19.093] Timer tick, asking Buildbucket for the build status
[00:54:19.314] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:19.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:38:0) after 1m0s
[00:55:19.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:38:0)
[00:55:19.409] Timer tick, asking Buildbucket for the build status
[00:55:19.513] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:19.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:39:0) after 1m0s
[00:56:19.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:39:0)
[00:56:19.608] Timer tick, asking Buildbucket for the build status
[00:56:19.817] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:19.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:40:0) after 1m0s
[00:57:19.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:40:0)
[00:57:19.947] Timer tick, asking Buildbucket for the build status
[00:57:20.055] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:20.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:41:0) after 1m0s
[00:58:20.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:41:0)
[00:58:20.209] Timer tick, asking Buildbucket for the build status
[00:58:21.345] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:21.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:42:0) after 1m0s
[00:59:21.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:42:0)
[00:59:21.406] Timer tick, asking Buildbucket for the build status
[00:59:21.683] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:21.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:43:0) after 1m0s
[01:00:21.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:43:0)
[01:00:21.805] Timer tick, asking Buildbucket for the build status
[01:00:21.858] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:21.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:44:0) after 1m0s
[01:01:21.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:44:0)
[01:01:21.938] Timer tick, asking Buildbucket for the build status
[01:01:22.006] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:22.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:45:0) after 1m0s
[01:02:22.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:45:0)
[01:02:22.034] Timer tick, asking Buildbucket for the build status
[01:02:22.106] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:22.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:46:0) after 1m0s
[01:03:22.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:46:0)
[01:03:22.145] Timer tick, asking Buildbucket for the build status
[01:03:22.274] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:22.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:47:0) after 1m0s
[01:04:22.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:47:0)
[01:04:22.277] Timer tick, asking Buildbucket for the build status
[01:04:22.428] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:22.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:48:0) after 1m0s
[01:05:22.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:48:0)
[01:05:22.568] Timer tick, asking Buildbucket for the build status
[01:05:22.644] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:22.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:49:0) after 1m0s
[01:06:22.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:49:0)
[01:06:22.816] Timer tick, asking Buildbucket for the build status
[01:06:22.933] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:22.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:50:0) after 1m0s
[01:07:23.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:50:0)
[01:07:23.119] Timer tick, asking Buildbucket for the build status
[01:07:23.299] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:23.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:51:0) after 1m0s
[01:08:23.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:51:0)
[01:08:23.370] Timer tick, asking Buildbucket for the build status
[01:08:23.466] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:23.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:52:0) after 1m0s
[01:09:23.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:52:0)
[01:09:23.606] Timer tick, asking Buildbucket for the build status
[01:09:23.718] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:23.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:53:0) after 1m0s
[01:10:23.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:53:0)
[01:10:23.737] Timer tick, asking Buildbucket for the build status
[01:10:23.900] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:23.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:54:0) after 1m0s
[01:11:24.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:54:0)
[01:11:24.035] Timer tick, asking Buildbucket for the build status
[01:11:27.756] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:27.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:55:0) after 1m0s
[01:12:27.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:55:0)
[01:12:27.851] Timer tick, asking Buildbucket for the build status
[01:12:28.009] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:28.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:56:0) after 1m0s
[01:13:28.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:56:0)
[01:13:28.075] Timer tick, asking Buildbucket for the build status
[01:13:28.194] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:28.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:57:0) after 1m0s
[01:14:28.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:57:0)
[01:14:28.402] Timer tick, asking Buildbucket for the build status
[01:14:28.648] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:28.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:58:0) after 1m0s
[01:15:28.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:58:0)
[01:15:28.829] Timer tick, asking Buildbucket for the build status
[01:15:28.943] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:28.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:59:0) after 1m0s
[01:16:29.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:59:0)
[01:16:29.042] Timer tick, asking Buildbucket for the build status
[01:16:29.146] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:29.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:60:0) after 1m0s
[01:17:29.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:60:0)
[01:17:29.303] Timer tick, asking Buildbucket for the build status
[01:17:29.391] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:29.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:61:0) after 1m0s
[01:18:29.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:61:0)
[01:18:29.456] Timer tick, asking Buildbucket for the build status
[01:18:30.011] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:30.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:62:0) after 1m0s
[01:19:30.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:62:0)
[01:19:30.012] Timer tick, asking Buildbucket for the build status
[01:19:30.187] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:30.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:63:0) after 1m0s
[01:20:30.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:63:0)
[01:20:30.205] Timer tick, asking Buildbucket for the build status
[01:20:30.567] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:30.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:64:0) after 1m0s
[01:21:30.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:64:0)
[01:21:30.635] Timer tick, asking Buildbucket for the build status
[01:21:30.845] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:30.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:65:0) after 1m0s
[01:22:30.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:65:0)
[01:22:30.939] Timer tick, asking Buildbucket for the build status
[01:22:31.088] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:31.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:66:0) after 1m0s
[01:23:31.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:66:0)
[01:23:31.194] Timer tick, asking Buildbucket for the build status
[01:23:31.568] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:31.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:67:0) after 1m0s
[01:24:31.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:67:0)
[01:24:31.654] Timer tick, asking Buildbucket for the build status
[01:24:31.886] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:31.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:68:0) after 1m0s
[01:25:31.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:68:0)
[01:25:31.955] Timer tick, asking Buildbucket for the build status
[01:25:32.427] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:32.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:69:0) after 1m0s
[01:26:32.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:69:0)
[01:26:32.475] Timer tick, asking Buildbucket for the build status
[01:26:32.599] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:32.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:70:0) after 1m0s
[01:27:32.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:70:0)
[01:27:32.606] Timer tick, asking Buildbucket for the build status
[01:27:32.924] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:32.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:71:0) after 1m0s
[01:28:32.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:71:0)
[01:28:32.963] Timer tick, asking Buildbucket for the build status
[01:28:33.336] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:33.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:72:0) after 1m0s
[01:29:33.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:72:0)
[01:29:33.347] Timer tick, asking Buildbucket for the build status
[01:29:33.451] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:33.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:73:0) after 1m0s
[01:30:33.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:73:0)
[01:30:33.496] Timer tick, asking Buildbucket for the build status
[01:30:34.423] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:34.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:74:0) after 1m0s
[01:31:34.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:74:0)
[01:31:34.544] Timer tick, asking Buildbucket for the build status
[01:31:35.892] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:35.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:75:0) after 1m0s
[01:32:35.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:75:0)
[01:32:35.997] Timer tick, asking Buildbucket for the build status
[01:32:36.173] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:36.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:76:0) after 1m0s
[01:33:36.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:76:0)
[01:33:36.212] Timer tick, asking Buildbucket for the build status
[01:33:36.293] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:36.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:77:0) after 1m0s
[01:34:36.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:77:0)
[01:34:36.330] Timer tick, asking Buildbucket for the build status
[01:34:36.519] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:36.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:78:0) after 1m0s
[01:35:36.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:78:0)
[01:35:36.651] Timer tick, asking Buildbucket for the build status
[01:35:37.865] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:37.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:79:0) after 1m0s
[01:36:37.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:79:0)
[01:36:37.888] Timer tick, asking Buildbucket for the build status
[01:36:38.338] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:38.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:80:0) after 1m0s
[01:37:38.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:80:0)
[01:37:38.354] Timer tick, asking Buildbucket for the build status
[01:37:38.515] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:38.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:81:0) after 1m0s
[01:38:38.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:81:0)
[01:38:38.619] Timer tick, asking Buildbucket for the build status
[01:38:39.321] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:82:0) after 1m0s
[01:39:39.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:82:0)
[01:39:39.344] Timer tick, asking Buildbucket for the build status
[01:39:39.469] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:39.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:83:0) after 1m0s
[01:40:39.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:83:0)
[01:40:39.535] Timer tick, asking Buildbucket for the build status
[01:40:39.752] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:39.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:84:0) after 1m0s
[01:41:39.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:84:0)
[01:41:39.804] Timer tick, asking Buildbucket for the build status
[01:41:39.938] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:39.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:85:0) after 1m0s
[01:42:40.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:85:0)
[01:42:40.037] Timer tick, asking Buildbucket for the build status
[01:42:40.130] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:40.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:86:0) after 1m0s
[01:43:40.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:86:0)
[01:43:40.182] Timer tick, asking Buildbucket for the build status
[01:43:40.252] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:40.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:87:0) after 1m0s
[01:44:40.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:87:0)
[01:44:40.277] Timer tick, asking Buildbucket for the build status
[01:44:40.380] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:40.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:88:0) after 1m0s
[01:45:40.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:88:0)
[01:45:40.439] Timer tick, asking Buildbucket for the build status
[01:45:40.618] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:89:0) after 1m0s
[01:46:40.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:89:0)
[01:46:40.684] Timer tick, asking Buildbucket for the build status
[01:46:40.854] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:40.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:90:0) after 1m0s
[01:47:41.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:90:0)
[01:47:41.155] Timer tick, asking Buildbucket for the build status
[01:47:41.311] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:41.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:91:0) after 1m0s
[01:48:41.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:91:0)
[01:48:41.587] Timer tick, asking Buildbucket for the build status
[01:48:41.754] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:41.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:92:0) after 1m0s
[01:49:41.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:92:0)
[01:49:41.976] Timer tick, asking Buildbucket for the build status
[01:49:42.084] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:42.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:93:0) after 1m0s
[01:50:42.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:93:0)
[01:50:42.394] Timer tick, asking Buildbucket for the build status
[01:50:42.692] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:42.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:94:0) after 1m0s
[01:51:42.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:94:0)
[01:51:42.801] Timer tick, asking Buildbucket for the build status
[01:51:42.930] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:42.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:95:0) after 1m0s
[01:52:43.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:95:0)
[01:52:43.132] Timer tick, asking Buildbucket for the build status
[01:52:43.242] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:43.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:96:0) after 1m0s
[01:53:43.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:96:0)
[01:53:43.437] Timer tick, asking Buildbucket for the build status
[01:53:43.536] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:43.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:97:0) after 1m0s
[01:54:43.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:97:0)
[01:54:43.646] Timer tick, asking Buildbucket for the build status
[01:54:43.783] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:43.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:98:0) after 1m0s
[01:55:43.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:98:0)
[01:55:43.823] Timer tick, asking Buildbucket for the build status
[01:55:44.163] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:44.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:99:0) after 1m0s
[01:56:44.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:99:0)
[01:56:44.297] Timer tick, asking Buildbucket for the build status
[01:56:44.909] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:44.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:100:0) after 1m0s
[01:57:45.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:100:0)
[01:57:45.048] Timer tick, asking Buildbucket for the build status
[01:57:45.143] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:45.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:101:0) after 1m0s
[01:58:45.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:101:0)
[01:58:45.162] Timer tick, asking Buildbucket for the build status
[01:58:45.716] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:45.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:102:0) after 1m0s
[01:59:45.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:102:0)
[01:59:45.835] Timer tick, asking Buildbucket for the build status
[01:59:46.802] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:46.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:103:0) after 1m0s
[02:00:46.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:103:0)
[02:00:46.851] Timer tick, asking Buildbucket for the build status
[02:00:46.927] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:46.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:104:0) after 1m0s
[02:01:47.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:104:0)
[02:01:47.070] Timer tick, asking Buildbucket for the build status
[02:01:47.154] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:47.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:105:0) after 1m0s
[02:02:47.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:105:0)
[02:02:47.196] Timer tick, asking Buildbucket for the build status
[02:02:47.272] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:47.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:106:0) after 1m0s
[02:03:47.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:106:0)
[02:03:47.299] Timer tick, asking Buildbucket for the build status
[02:03:47.509] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:47.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:107:0) after 1m0s
[02:04:47.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:107:0)
[02:04:47.545] Timer tick, asking Buildbucket for the build status
[02:04:48.393] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:48.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:108:0) after 1m0s
[02:05:48.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:108:0)
[02:05:48.507] Timer tick, asking Buildbucket for the build status
[02:05:48.608] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:48.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:109:0) after 1m0s
[02:06:48.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:109:0)
[02:06:48.844] Timer tick, asking Buildbucket for the build status
[02:06:49.070] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:110:0) after 1m0s
[02:07:49.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:110:0)
[02:07:49.239] Timer tick, asking Buildbucket for the build status
[02:07:49.316] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:49.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:111:0) after 1m0s
[02:08:49.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:111:0)
[02:08:49.362] Timer tick, asking Buildbucket for the build status
[02:08:49.442] Build 8856181328482052016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:49.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021611390445889360:112:0) after 1m0s
[02:08:54.238] Received PubSub notification, asking Buildbucket for the build status
[02:08:54.336] Build 8856181328482052016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:08:54.336] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612490932237471",
"created_by": "project:chromium",
"created_ts": "1612484347798353",
"id": "8856181328482052016",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\", \"project\": \"chromium/src\"}], \"$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-central1-b-8-54-fngo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22484, \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"bdbf98afca9fba5ca863c2c963ab10edae45b1cc\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\", \"got_revision_cp\": \"refs/heads/master@{#850860}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"a71154a1ede8ce4ce90eb23ede108aef7e94691d\", \"got_v8_revision_cp\": \"refs/heads/9.0.133@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"88f7273b9e1b018ec3dd1a45db0674adcf109d1f\", \"swarm_hashes_refs/heads/master(at){#850860}_without_patch\": {\"absl_hardening_tests\": \"d3042f38f2649e774248256b88831538dda15d24\", \"accessibility_unittests\": \"cb3e659c0b2a955a122c354631b74fa5699306c0\", \"angle_unittests\": \"6329f0c059fcd98823d5759e16d27c73d7764898\", \"app_list_unittests\": \"77dcd20f125f406d315094d70aa76bec04885705\", \"app_shell_unittests\": \"2fcdaf1b28279aa122721d138b9f6ddcf2e9da07\", \"ash_unittests\": \"9e2e6ff4fcf82442c80ba4fde7c137f0cf5372dd\", \"aura_unittests\": \"9b17e8412d531039dd44228466cfccf103cbc83f\", \"base_unittests\": \"8b811bae5b91530f3722923977a1e0ec667de023\", \"base_util_unittests\": \"121f4d45ad3c600c901bf48066a2de658d404361\", \"blink_common_unittests\": \"da7489fc7d1c841d248eda462acab612b0cf59c0\", \"blink_fuzzer_unittests\": \"e56d62826b5e8d70ec48005b369dc74eb63f49f6\", \"blink_heap_unittests\": \"92884f825aee1874c39e963c74553517cd2989a8\", \"blink_platform_unittests\": \"32be85bb00bef22eb7c15ec61f03a356d6a51f75\", \"blink_unittests\": \"718b37c5653864ab0cb353db64317c07c94e7b90\", \"boringssl_crypto_tests\": \"3ab96408c2436bb4e283948eb3569077c271254e\", \"boringssl_ssl_tests\": \"e7a0c0e0db2860201df9589e511b7024e4dfea35\", \"browser_tests\": \"5294c5b7750f82c594a3407db72481034e3fd2b2\", \"capture_unittests\": \"b77a826401ca323fae99469241918270549ee5fd\", \"cast_unittests\": \"8017280eb8951f757fb61374812f46065d65db7e\", \"cc_unittests\": \"6ce625c552187a733881707c70ebeedcf93e1da6\", \"chrome_app_unittests\": \"3b6e796613e1ff8f37064f02899435e2fc3dd188\", \"chromedriver_unittests\": \"e0360f71859865444cbd6bf3b3892fb23b1b4de0\", \"chromeos_components_unittests\": \"02f79bea86065e139a104fac03f9d2772d8cfb1d\", \"chromeos_unittests\": \"4b4759551e5c9023ea7a101865a86211a35214c8\", \"components_browsertests\": \"1dcdf7bbc78dff72388490b9f1bad4035f45ea18\", \"components_unittests\": \"2cde096b0ce87af4f13901607ded33896f4446bc\", \"compositor_unittests\": \"dbd92e48a958bcfe0e6ff1e17e3210fa1f8c3eef\", \"content_browsertests\": \"99782419a6c93cacaf9fc818a9dd64cdd5aafafe\", \"content_unittests\": \"4ef05f948e3e7cbd883508343d1de2e2d3a6ed27\", \"crashpad_tests\": \"e58d9682494baa79b63b35878bfd8787ffe0026f\", \"crypto_unittests\": \"0ed1f0f533fbc601038b4357fe5595fe8924e211\", \"dbus_unittests\": \"8d36b60afa5e56c08e6dda0fcdfe2987e3ca42ca\", \"device_unittests\": \"a82fe8c2dd605ec8a1c2db017979f38170ab1e28\", \"display_unittests\": \"b8b1489a024db5aed69365023522af913a62c603\", \"events_unittests\": \"e0e31f8b872d0e10899fd1c2c8451380fa65fd8f\", \"exo_unittests\": \"49c1f67b7c3224b0e6e0fdd5b55a5fa369c63585\", \"extensions_browsertests\": \"07ac15625285e129e1590df6971367ea6b778916\", \"extensions_unittests\": \"b518841231e640c300a6b754c0a54b15e730b649\", \"filesystem_service_unittests\": \"797a58b2e389d5288c7a2b65905498519af2a870\", \"gcm_unit_tests\": \"bd1d1ae505a2185e2a1f70a2f240eef8f3e5deac\", \"gfx_unittests\": \"3583c05e98daf89b0215c77c956f9d774c0499f6\", \"gin_unittests\": \"e8beb32e9eef8e3e5e4b8e4b00594739c86055a8\", \"gl_unittests_ozone\": \"90eeed31afbb05442359fd7e45f8d960bf02aa7a\", \"google_apis_unittests\": \"1a74a8b986cedb3eda121efc0c89842e47161465\", \"gpu_unittests\": \"d56e1a59b87712ae667df4d31b939c4e3b8d08f7\", \"gwp_asan_unittests\": \"237bf8bf78fde0c50c4e2385d3d4a7745e70b93f\", \"interactive_ui_tests\": \"b7d774ddcf3af1108c95319eebd073dc7cb2fb66\", \"ipc_tests\": \"face8f8a726ac4fd7d020c16406d9c2a84ef14c7\", \"jingle_unittests\": \"679773cf0a7c3a971d24bf0fa41272c459fdbe2f\", \"keyboard_unittests\": \"9a5e5ac81b6fcb4a38c49fcfbfbba3b5d8ac2451\", \"latency_unittests\": \"fa54b28bdc347da550d94a105651fe447572cda1\", \"libjingle_xmpp_unittests\": \"91920e0d135a96bffea12c82eac70c9920e0187f\", \"liburlpattern_unittests\": \"c739c0984b1446a0a3e721dccdb95b7bae56782d\", \"media_blink_unittests\": \"bed6e6b0eee70aa673b894393012fae9fd6bcd55\", \"media_unittests\": \"8868d8a42b85354dfaad09b9e3e848227c7f0fe1\", \"message_center_unittests\": \"c2651e463647d02563a663a8e6f581a10515e6fe\", \"midi_unittests\": \"1e157aa735a4719f1ba837ca2d3cf597a312c8e2\", \"mojo_core_unittests\": \"ff8ec6962f8e581cb1e18a0c3ca834a4123781e2\", \"mojo_unittests\": \"f4f39ff08e1d2778f3117d114a706cae839eb86a\", \"nacl_helper_nonsfi_unittests\": \"bd9b6db1d377d74a3e627fea4633c5744cf2d37c\", \"nacl_loader_unittests\": \"d4338a19edf403776b17e610ef373133133a4bd4\", \"native_theme_unittests\": \"8cbed240c6ab6de7bb7e97d4e921d9c2ee041e5c\", \"net_unittests\": \"6dfeaee8ee65eaf93aa59ba0d63874a512f7d305\", \"ozone_gl_unittests\": \"10b1e7ee379950f9e1fc67f184fed7e3192996eb\", \"ozone_unittests\": \"e21c8628d5165574f664650d004706ba84737555\", \"ozone_x11_unittests\": \"2181ab2b03b73fbc5cfb78878553cf2f9c44ca21\", \"pdf_unittests\": \"7d5e90027681e770911b8a58ea74208a21828edb\", \"perfetto_unittests\": \"7b1659538f39304c2f47fe2e61adba0425d1ec01\", \"ppapi_unittests\": \"145fe6d8299f296defc3e7a7e591d02963bb0588\", \"printing_unittests\": \"a5c6d8b6b75bb04c1aa6443fb8da8adbce0873e5\", \"remoting_unittests\": \"c5c797d0ccdfb7b655e137b85f3d38a34c943f49\", \"sandbox_linux_unittests\": \"236f2150c5da741577e4ff062c2fcafd0c5a71c9\", \"service_manager_unittests\": \"a2987375a948a3a580e68bf92107a513d8ecebe4\", \"services_unittests\": \"5df78f8e1af8d72831321664b002d6fe6f4d46f1\", \"shell_dialogs_unittests\": \"07e532fe7717ce7d93d9f8deb47395626eea2056\", \"shell_encryption_unittests\": \"04c7e10487b7ec0ed79cb85881d28cbad88a1cd7\", \"skia_unittests\": \"bfdd9e1bc1a60404db04134d94ec4324b4fae76f\", \"snapshot_unittests\": \"2d6a37e0da6ff254f63b3ef7cfa088855a863edd\", \"sql_unittests\": \"8ba6c5113e411c3d54516c9aada3434baab72bde\", \"storage_unittests\": \"b3483d97b7353e89737a7e59033e0e65d9afea9b\", \"sync_integration_tests\": \"e20ba1d1b2bb34a29f9addbc0d33d7e520c3a648\", \"traffic_annotation_auditor_unittests\": \"5e63aa5625e0e022e10c1cf2465f92214a823060\", \"ui_base_unittests\": \"9ec9620d9a3c73e825158ebe31935b5df0a58fc7\", \"ui_chromeos_unittests\": \"91aa0d71a2d07cf231ecd156661be0e655d96a55\", \"ui_touch_selection_unittests\": \"9518775d9375686cdb0d5de5f3514f48516f2b5f\", \"unit_tests\": \"0d665e33aaf88da648a73a076feb5b9b7c97988f\", \"url_unittests\": \"c4128d07621d04b295e489a7ce49243246bf1b66\", \"usage_time_limit_unittests\": \"aa2b0c141053a036faa0dbfe41c1d6425aee69c3\", \"views_unittests\": \"9a253eb07f31ab5ad9b5ff7204fac31f930bdc85\", \"viz_unittests\": \"12bd4b86bec8f0d490792471468b9136f5f77867\", \"wm_unittests\": \"bccac28128a98dfc0cd1b79eb80dc1e652375e9e\", \"wtf_unittests\": \"853d1f87a547a3fedd479ee315d6a3b0169de0ed\", \"zlib_unittests\": \"0ac935ec81fa82030af2c3b87e0c2d9aa9213057\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_349e20fa0346c22afd9d85eafc166958d65f8d3eb174d2113a3f89c2e822dead_v2\", \"builder_37a6efb75484d21403d66fa62f2f0e5111772231983ba4ec0093d39fca548be8_v2\", \"builder_4a2b13219f689d34aa475139e9738b55334bf25923df6aa4978f2ee96f164d56_v2\", \"builder_573c606f18492d6cde6d25a02b12203725732038b1c2909b0b80126d2afb25c2_v2\", \"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"builder_7ddc1ece4c938056809d45345930c5d8ea6799936d2255389af4f16219f397ac_v2\", \"builder_bbe6c22469d6b45d8f49350e3da0912e9331dc294c727d05ae5755d0a8b95710_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-54-fngo\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612484715146870",
"status": "COMPLETED",
"status_changed_ts": "1612490933365265",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22484",
"builder:linux-chromeos-dbg",
"buildset:commit/git/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88f7273b9e1b018ec3dd1a45db0674adcf109d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021611390445889360",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856181328482052016/+/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:51886013533f5c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612490933370522",
"url": "https://ci.chromium.org/b/8856181328482052016",
"utcnow_ts": "1612490934326898"
}
}
[02:08:54.336] Invocation finished in 1h49m47.795400557s with status SUCCEEDED