[15:10:41.130] New invocation is queued and will start shortly
[15:10:42.271] Starting the invocation (attempt 1)
[15:10:42.312] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:10:42.312] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:10:42.312] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018656194395296448",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018656194395296448",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"gitiles_ref:refs/heads/master"
]
}
[15:10:42.948] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615302642395977",
"id": "8853226132406077632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615302642852863",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22923",
"builder:linux-chromeos-dbg",
"buildset:commit/git/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018656194395296448",
"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/8853226132406077632/+/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": "1615302642853073",
"url": "https://ci.chromium.org/b/8853226132406077632",
"utcnow_ts": "1615302642940298"
}
}
[15:10:42.948] Task URL: https://ci.chromium.org/b/8853226132406077632
[15:10:42.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:2:0) after 1m0s
[15:11:43.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:2:0)
[15:11:43.114] Timer tick, asking Buildbucket for the build status
[15:11:43.312] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:11:43.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:3:0) after 1m0s
[15:12:43.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:3:0)
[15:12:43.557] Timer tick, asking Buildbucket for the build status
[15:12:44.462] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:12:44.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:4:0) after 1m0s
[15:13:44.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:4:0)
[15:13:44.836] Timer tick, asking Buildbucket for the build status
[15:13:44.896] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:44.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:5:0) after 1m0s
[15:14:45.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:5:0)
[15:14:45.126] Timer tick, asking Buildbucket for the build status
[15:14:45.216] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:45.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:6:0) after 1m0s
[15:15:45.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:6:0)
[15:15:45.337] Timer tick, asking Buildbucket for the build status
[15:15:45.401] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:45.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:7:0) after 1m0s
[15:16:45.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:7:0)
[15:16:45.472] Timer tick, asking Buildbucket for the build status
[15:16:45.579] Build 8853226132406077632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:45.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:8:0) after 1m0s
[15:16:47.761] Received PubSub notification, asking Buildbucket for the build status
[15:16:47.843] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:45.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:8:0)
[15:17:45.667] Timer tick, asking Buildbucket for the build status
[15:17:45.753] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:45.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:10:0) after 1m0s
[15:18:45.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:10:0)
[15:18:45.882] Timer tick, asking Buildbucket for the build status
[15:18:45.966] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:45.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:11:0) after 1m0s
[15:19:46.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:11:0)
[15:19:46.006] Timer tick, asking Buildbucket for the build status
[15:19:46.256] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:46.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:12:0) after 1m0s
[15:20:46.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:12:0)
[15:20:46.292] Timer tick, asking Buildbucket for the build status
[15:20:46.444] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:46.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:13:0) after 1m0s
[15:21:46.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:13:0)
[15:21:46.419] Timer tick, asking Buildbucket for the build status
[15:21:46.769] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:46.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:14:0) after 1m0s
[15:22:46.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:14:0)
[15:22:46.811] Timer tick, asking Buildbucket for the build status
[15:22:46.960] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:46.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:15:0) after 1m0s
[15:23:46.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:15:0)
[15:23:46.976] Timer tick, asking Buildbucket for the build status
[15:23:47.041] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:47.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:16:0) after 1m0s
[15:24:47.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:16:0)
[15:24:47.067] Timer tick, asking Buildbucket for the build status
[15:24:47.295] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:47.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:17:0) after 1m0s
[15:25:47.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:17:0)
[15:25:47.321] Timer tick, asking Buildbucket for the build status
[15:25:47.428] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:47.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:18:0) after 1m0s
[15:26:47.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:18:0)
[15:26:47.445] Timer tick, asking Buildbucket for the build status
[15:26:47.643] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:47.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:19:0) after 1m0s
[15:27:47.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:19:0)
[15:27:47.663] Timer tick, asking Buildbucket for the build status
[15:27:47.732] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:47.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:20:0) after 1m0s
[15:28:47.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:20:0)
[15:28:47.842] Timer tick, asking Buildbucket for the build status
[15:28:47.929] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:47.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:21:0) after 1m0s
[15:29:47.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:21:0)
[15:29:47.947] Timer tick, asking Buildbucket for the build status
[15:29:48.006] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:48.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:22:0) after 1m0s
[15:30:48.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:22:0)
[15:30:48.025] Timer tick, asking Buildbucket for the build status
[15:30:48.096] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:48.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:23:0) after 1m0s
[15:31:48.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:23:0)
[15:31:48.139] Timer tick, asking Buildbucket for the build status
[15:31:48.259] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:48.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:24:0) after 1m0s
[15:32:48.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:24:0)
[15:32:48.255] Timer tick, asking Buildbucket for the build status
[15:32:48.331] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:48.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:25:0) after 1m0s
[15:33:48.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:25:0)
[15:33:48.346] Timer tick, asking Buildbucket for the build status
[15:33:48.427] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:48.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:26:0) after 1m0s
[15:34:48.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:26:0)
[15:34:48.786] Timer tick, asking Buildbucket for the build status
[15:34:48.860] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:48.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:27:0) after 1m0s
[15:35:48.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:27:0)
[15:35:48.879] Timer tick, asking Buildbucket for the build status
[15:35:49.022] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:49.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:28:0) after 1m0s
[15:36:49.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:28:0)
[15:36:49.044] Timer tick, asking Buildbucket for the build status
[15:36:49.178] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:49.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:29:0) after 1m0s
[15:37:49.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:29:0)
[15:37:49.226] Timer tick, asking Buildbucket for the build status
[15:37:49.497] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:30:0) after 1m0s
[15:38:49.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:30:0)
[15:38:49.546] Timer tick, asking Buildbucket for the build status
[15:38:49.619] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:49.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:31:0) after 1m0s
[15:39:49.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:31:0)
[15:39:49.618] Timer tick, asking Buildbucket for the build status
[15:39:49.686] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:49.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:32:0) after 1m0s
[15:40:49.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:32:0)
[15:40:49.728] Timer tick, asking Buildbucket for the build status
[15:40:49.849] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:49.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:33:0) after 1m0s
[15:41:49.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:33:0)
[15:41:49.869] Timer tick, asking Buildbucket for the build status
[15:41:50.051] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:50.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:34:0) after 1m0s
[15:42:50.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:34:0)
[15:42:50.082] Timer tick, asking Buildbucket for the build status
[15:42:50.162] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:50.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:35:0) after 1m0s
[15:43:50.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:35:0)
[15:43:50.186] Timer tick, asking Buildbucket for the build status
[15:43:50.960] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:50.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:36:0) after 1m0s
[15:44:50.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:36:0)
[15:44:50.977] Timer tick, asking Buildbucket for the build status
[15:44:51.059] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:51.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:37:0) after 1m0s
[15:45:51.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:37:0)
[15:45:51.085] Timer tick, asking Buildbucket for the build status
[15:45:51.416] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:51.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:38:0) after 1m0s
[15:46:51.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:38:0)
[15:46:51.465] Timer tick, asking Buildbucket for the build status
[15:46:51.584] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:51.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:39:0) after 1m0s
[15:47:51.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:39:0)
[15:47:51.631] Timer tick, asking Buildbucket for the build status
[15:47:51.736] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:51.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:40:0) after 1m0s
[15:48:51.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:40:0)
[15:48:51.755] Timer tick, asking Buildbucket for the build status
[15:48:51.916] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:51.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:41:0) after 1m0s
[15:49:51.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:41:0)
[15:49:51.950] Timer tick, asking Buildbucket for the build status
[15:49:52.169] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:52.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:42:0) after 1m0s
[15:50:52.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:42:0)
[15:50:52.258] Timer tick, asking Buildbucket for the build status
[15:50:52.348] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:52.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:43:0) after 1m0s
[15:51:52.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:43:0)
[15:51:52.509] Timer tick, asking Buildbucket for the build status
[15:51:52.588] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:52.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:44:0) after 1m0s
[15:52:52.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:44:0)
[15:52:52.765] Timer tick, asking Buildbucket for the build status
[15:52:52.844] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:52.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:45:0) after 1m0s
[15:53:52.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:45:0)
[15:53:52.924] Timer tick, asking Buildbucket for the build status
[15:53:53.194] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:53.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:46:0) after 1m0s
[15:54:53.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:46:0)
[15:54:53.200] Timer tick, asking Buildbucket for the build status
[15:54:53.273] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:53.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:47:0) after 1m0s
[15:55:53.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:47:0)
[15:55:53.286] Timer tick, asking Buildbucket for the build status
[15:55:53.598] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:53.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:48:0) after 1m0s
[15:56:53.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:48:0)
[15:56:53.630] Timer tick, asking Buildbucket for the build status
[15:56:53.864] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:53.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:49:0) after 1m0s
[15:57:53.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:49:0)
[15:57:53.883] Timer tick, asking Buildbucket for the build status
[15:57:54.093] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:54.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:50:0) after 1m0s
[15:58:54.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:50:0)
[15:58:54.112] Timer tick, asking Buildbucket for the build status
[15:58:54.270] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:54.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:51:0) after 1m0s
[15:59:54.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:51:0)
[15:59:54.362] Timer tick, asking Buildbucket for the build status
[15:59:54.478] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:54.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:52:0) after 1m0s
[16:00:54.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:52:0)
[16:00:54.468] Timer tick, asking Buildbucket for the build status
[16:00:54.580] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:54.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:53:0) after 1m0s
[16:01:54.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:53:0)
[16:01:54.613] Timer tick, asking Buildbucket for the build status
[16:01:54.971] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:54.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:54:0) after 1m0s
[16:02:54.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:54:0)
[16:02:54.981] Timer tick, asking Buildbucket for the build status
[16:02:55.065] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:55.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:55:0) after 1m0s
[16:03:55.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:55:0)
[16:03:55.212] Timer tick, asking Buildbucket for the build status
[16:03:55.305] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:55.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:56:0) after 1m0s
[16:04:55.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:56:0)
[16:04:55.442] Timer tick, asking Buildbucket for the build status
[16:04:55.913] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:55.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:57:0) after 1m0s
[16:05:55.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:57:0)
[16:05:55.931] Timer tick, asking Buildbucket for the build status
[16:05:56.042] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:56.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:58:0) after 1m0s
[16:06:56.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:58:0)
[16:06:56.080] Timer tick, asking Buildbucket for the build status
[16:06:56.152] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:56.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:59:0) after 1m0s
[16:07:56.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:59:0)
[16:07:56.190] Timer tick, asking Buildbucket for the build status
[16:07:56.334] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:56.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:60:0) after 1m0s
[16:08:56.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:60:0)
[16:08:56.432] Timer tick, asking Buildbucket for the build status
[16:08:56.556] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:56.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:61:0) after 1m0s
[16:09:56.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:61:0)
[16:09:56.782] Timer tick, asking Buildbucket for the build status
[16:09:56.922] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:56.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:62:0) after 1m0s
[16:10:56.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:62:0)
[16:10:56.899] Timer tick, asking Buildbucket for the build status
[16:10:57.080] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:57.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:63:0) after 1m0s
[16:11:57.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:63:0)
[16:11:57.154] Timer tick, asking Buildbucket for the build status
[16:11:57.234] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:57.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:64:0) after 1m0s
[16:12:57.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:64:0)
[16:12:57.286] Timer tick, asking Buildbucket for the build status
[16:12:57.370] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:57.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:65:0) after 1m0s
[16:13:57.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:65:0)
[16:13:57.379] Timer tick, asking Buildbucket for the build status
[16:13:57.538] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:57.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:66:0) after 1m0s
[16:14:57.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:66:0)
[16:14:57.611] Timer tick, asking Buildbucket for the build status
[16:14:57.704] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:57.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:67:0) after 1m0s
[16:15:57.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:67:0)
[16:15:57.747] Timer tick, asking Buildbucket for the build status
[16:15:57.835] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:57.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:68:0) after 1m0s
[16:16:57.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:68:0)
[16:16:57.887] Timer tick, asking Buildbucket for the build status
[16:16:57.982] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:57.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:69:0) after 1m0s
[16:17:58.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:69:0)
[16:17:58.461] Timer tick, asking Buildbucket for the build status
[16:17:58.598] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:58.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:70:0) after 1m0s
[16:18:58.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:70:0)
[16:18:58.887] Timer tick, asking Buildbucket for the build status
[16:18:58.983] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:58.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:71:0) after 1m0s
[16:19:59.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:71:0)
[16:19:59.536] Timer tick, asking Buildbucket for the build status
[16:19:59.647] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:59.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:72:0) after 1m0s
[16:20:59.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:72:0)
[16:20:59.912] Timer tick, asking Buildbucket for the build status
[16:21:00.100] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:00.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:73:0) after 1m0s
[16:22:00.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:73:0)
[16:22:00.145] Timer tick, asking Buildbucket for the build status
[16:22:01.066] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:01.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:74:0) after 1m0s
[16:23:01.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:74:0)
[16:23:01.368] Timer tick, asking Buildbucket for the build status
[16:23:01.443] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:01.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:75:0) after 1m0s
[16:24:01.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:75:0)
[16:24:01.663] Timer tick, asking Buildbucket for the build status
[16:24:01.736] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:01.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:76:0) after 1m0s
[16:25:02.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:76:0)
[16:25:02.328] Timer tick, asking Buildbucket for the build status
[16:25:02.547] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:02.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:77:0) after 1m0s
[16:26:02.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:77:0)
[16:26:02.611] Timer tick, asking Buildbucket for the build status
[16:26:02.951] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:02.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:78:0) after 1m0s
[16:27:02.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:78:0)
[16:27:02.978] Timer tick, asking Buildbucket for the build status
[16:27:03.073] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:03.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:79:0) after 1m0s
[16:28:03.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:79:0)
[16:28:03.190] Timer tick, asking Buildbucket for the build status
[16:28:03.317] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:03.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:80:0) after 1m0s
[16:29:03.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:80:0)
[16:29:03.381] Timer tick, asking Buildbucket for the build status
[16:29:03.464] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:03.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:81:0) after 1m0s
[16:30:03.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:81:0)
[16:30:03.562] Timer tick, asking Buildbucket for the build status
[16:30:03.807] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:03.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:82:0) after 1m0s
[16:31:03.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:82:0)
[16:31:03.872] Timer tick, asking Buildbucket for the build status
[16:31:05.018] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:05.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:83:0) after 1m0s
[16:32:05.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:83:0)
[16:32:05.064] Timer tick, asking Buildbucket for the build status
[16:32:05.211] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:05.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:84:0) after 1m0s
[16:33:05.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:84:0)
[16:33:05.274] Timer tick, asking Buildbucket for the build status
[16:33:05.352] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:05.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:85:0) after 1m0s
[16:34:05.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:85:0)
[16:34:05.414] Timer tick, asking Buildbucket for the build status
[16:34:05.600] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:05.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:86:0) after 1m0s
[16:35:05.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:86:0)
[16:35:05.635] Timer tick, asking Buildbucket for the build status
[16:35:05.722] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:05.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:87:0) after 1m0s
[16:36:05.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:87:0)
[16:36:05.726] Timer tick, asking Buildbucket for the build status
[16:36:05.917] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:05.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:88:0) after 1m0s
[16:37:06.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:88:0)
[16:37:06.128] Timer tick, asking Buildbucket for the build status
[16:37:07.313] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:07.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:89:0) after 1m0s
[16:38:07.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:89:0)
[16:38:07.551] Timer tick, asking Buildbucket for the build status
[16:38:07.655] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:07.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:90:0) after 1m0s
[16:39:07.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:90:0)
[16:39:07.722] Timer tick, asking Buildbucket for the build status
[16:39:08.324] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:08.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:91:0) after 1m0s
[16:40:08.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:91:0)
[16:40:08.400] Timer tick, asking Buildbucket for the build status
[16:40:08.504] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:08.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:92:0) after 1m0s
[16:41:08.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:92:0)
[16:41:08.547] Timer tick, asking Buildbucket for the build status
[16:41:08.620] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:08.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:93:0) after 1m0s
[16:42:08.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:93:0)
[16:42:08.623] Timer tick, asking Buildbucket for the build status
[16:42:08.738] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:08.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:94:0) after 1m0s
[16:43:08.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:94:0)
[16:43:08.758] Timer tick, asking Buildbucket for the build status
[16:43:08.846] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:08.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:95:0) after 1m0s
[16:44:08.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:95:0)
[16:44:08.983] Timer tick, asking Buildbucket for the build status
[16:44:09.234] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:09.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:96:0) after 1m0s
[16:45:09.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:96:0)
[16:45:09.306] Timer tick, asking Buildbucket for the build status
[16:45:10.436] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:10.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:97:0) after 1m0s
[16:46:10.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:97:0)
[16:46:10.473] Timer tick, asking Buildbucket for the build status
[16:46:10.561] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:10.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:98:0) after 1m0s
[16:47:10.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:98:0)
[16:47:10.590] Timer tick, asking Buildbucket for the build status
[16:47:10.682] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:10.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:99:0) after 1m0s
[16:48:10.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:99:0)
[16:48:10.708] Timer tick, asking Buildbucket for the build status
[16:48:10.810] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:10.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:100:0) after 1m0s
[16:49:10.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:100:0)
[16:49:10.783] Timer tick, asking Buildbucket for the build status
[16:49:10.862] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:10.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:101:0) after 1m0s
[16:50:10.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:101:0)
[16:50:10.924] Timer tick, asking Buildbucket for the build status
[16:50:11.024] Build 8853226132406077632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:11.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018656194395296448:102:0) after 1m0s
[16:50:32.860] Received PubSub notification, asking Buildbucket for the build status
[16:50:33.003] Build 8853226132406077632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:50:33.003] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615308631135609",
"created_by": "project:chromium",
"created_ts": "1615302642395977",
"id": "8853226132406077632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\"}}",
"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\": \"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\", \"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-201-1nl3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22923, \"got_angle_revision\": \"faa3915ee3f06dade41a2bc260d850b99c5c6845\", \"got_dawn_revision\": \"7c3b5740db6be782ca4d2e78139568b67db1fc0f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\", \"got_revision_cp\": \"refs/heads/master@{#861150}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"023914a27b634063766fee96f2e6763513f5b059\", \"got_v8_revision\": \"5c86b9ed5fdab5a53c326ae37b77effcef0b67ff\", \"got_v8_revision_cp\": \"refs/heads/9.1.75@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"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\": \"51b302114c168d8d7ccebc2f1f42e12564fd9cf1\", \"swarm_hashes_refs/heads/master(at){#861150}_without_patch\": {\"absl_hardening_tests\": \"8e49e46e0d87d87fbb6ce0fd9b5a6acec830200f\", \"accessibility_unittests\": \"a23d41c44473b0fe7a0bdec5395570d0ee036f81\", \"angle_unittests\": \"d4a7976e4a6a492558001fdbc77080f39083c36b\", \"app_list_unittests\": \"57afd73ef20e5a8c6965b816aadd89787f00a644\", \"app_shell_unittests\": \"af040643039a4aaa88292f9a887841a7a5e5ad61\", \"ash_unittests\": \"2d054d54fbc5cbd86e19d47784f35e558377f903\", \"aura_unittests\": \"4792d0adee5e105420dd22c346da6a85130bdc96\", \"base_unittests\": \"ea007ee9ae8f96f9ddac64e477a445714c6dc6c8\", \"base_util_unittests\": \"b15d2e089b40c3de34abd26006324adaa71abee3\", \"blink_common_unittests\": \"051977f5c3346cf2cada52458b2b4a7307a3c9c3\", \"blink_fuzzer_unittests\": \"d64fb93dbf83db578e9ef48eb9d63d5034a4aa39\", \"blink_heap_unittests\": \"31a807e6d5720f2673b5bdf94c9b083ed583a55c\", \"blink_platform_unittests\": \"2d5b6e076da225a2e40f293fbc57c84cef98eb91\", \"blink_unittests\": \"6ce3acac437f6b4fae17c30b6f1776a973884c84\", \"boringssl_crypto_tests\": \"f41c5674d5b1565b976cf77b97c7e74f4a371f07\", \"boringssl_ssl_tests\": \"b518193c5d89281bd16f4adc59d7406fd8218aa9\", \"browser_tests\": \"d017983b272fab1b500f9ddf765cf3bf980d2e50\", \"capture_unittests\": \"fc0068b5eac1240e508f34b149a14af2adffe365\", \"cast_unittests\": \"2065c78ce2dfc6dbd4d03c795aa2eae40e1acbb3\", \"cc_unittests\": \"b1554c55b01f31240a3ec31026e507f3a5144d24\", \"chrome_app_unittests\": \"10045405b8dac67a2c7903a14afa3b01eec2698b\", \"chromedriver_unittests\": \"7cbbd6e99d813703934b599040101cf4031036b3\", \"chromeos_components_unittests\": \"662a007d3fcaeee8bfaace40d42965545a74a58c\", \"chromeos_unittests\": \"1ab55249d93bb0f37412e7eb3400555376bce52f\", \"color_unittests\": \"6c7c2f7814dce81f2ed5be10465c873db27d2b3f\", \"components_browsertests\": \"e7131a6558014e6ce41abc2e4eb78a38a3830333\", \"components_unittests\": \"4e52867db3e58671e40fe7eee6d1d09119ffdb15\", \"compositor_unittests\": \"89a19cec0c0c0798dc38461af8873d91302c7fa4\", \"content_browsertests\": \"298ff6fabf52bf6b6b1aedd6f75c67e16e24218c\", \"content_unittests\": \"abb36df4e0c766142baf1bdc4cc5c3ae6a3874aa\", \"crashpad_tests\": \"92719eee95989db87de7af7c395dfe5d6f5b62f3\", \"crypto_unittests\": \"a5b4893e6970fc0825b218e8c4c34072812c2caf\", \"dbus_unittests\": \"a0451f155a46e8d624606a62e073fe9b2c10fb0a\", \"device_unittests\": \"a6d826e0472633f6a8d30abc318bd0a76b7aa953\", \"display_unittests\": \"91508093c490700a7da8d0c2fef6ea7a6949ac92\", \"events_unittests\": \"7c179b2bc16be50335b295408e31b82a7abb20d8\", \"exo_unittests\": \"44c98ef2c60782759e2f0dc07a124d5f9afd5c4a\", \"extensions_browsertests\": \"2e705d4923394a0ec0089142e2d021300a82f28a\", \"extensions_unittests\": \"c276d9a7f8818531f8aa753861842a8a1b2d9860\", \"filesystem_service_unittests\": \"721f1d312f4f389cb77a005ab5696720c045d841\", \"gcm_unit_tests\": \"23d6d389d3fd07b9fe0750312c320d939ce216f0\", \"gfx_unittests\": \"ed232b0adca145db2f7525860b12be4897b15d45\", \"gin_unittests\": \"802b16a6743627888b1ec0e291ad09969ff1ae03\", \"gl_unittests_ozone\": \"0a43c1776d41b425d1f7f8b3cade11b7009549e8\", \"google_apis_unittests\": \"920c0833933e8c9704c7b71bd1bb89c8fdbc68ae\", \"gpu_unittests\": \"dfc52579c679efa243c5cb04f90107eb634dc29d\", \"gwp_asan_unittests\": \"da0bf2be92f8c8e8faeb70623728a65129d5f666\", \"interactive_ui_tests\": \"322f635ed6ca16089e78e2c868fd7bed196fcbc2\", \"ipc_tests\": \"c04e2438631173388cbc5d5a033fa06e5721eba3\", \"jingle_unittests\": \"11a8cbb60b7740a5ed57d9fa2f69ccae85f82c05\", \"keyboard_unittests\": \"ed4594779e933428e7ccb4a25ec62f3b950047d8\", \"latency_unittests\": \"fc30b2e859aa066939b1def92ac804e332f73be2\", \"libjingle_xmpp_unittests\": \"cd5c4e496aaa57f4100ebed23505e03beada577b\", \"liburlpattern_unittests\": \"33229578f994deff0f59ff1b136860a43f447572\", \"media_blink_unittests\": \"c311deae9e9467cb30979940aefb31b88eee7710\", \"media_unittests\": \"05d6db3becce24ed633c6b64dcde42a03bf8e317\", \"message_center_unittests\": \"80394d5cc002233f9e0f8a5915d2ed16e2d239fb\", \"midi_unittests\": \"ab8bcee89383faa93efc48e8197512918ef58eaa\", \"mojo_core_unittests\": \"f90981d2d176b73a9b89f5bea5e6c0be75831f49\", \"mojo_unittests\": \"58727cb181bf9bc2382334632289bd79081cbdfa\", \"nacl_helper_nonsfi_unittests\": \"ef661455643d53d855339c5306f951e095d79919\", \"nacl_loader_unittests\": \"ae6e4518e3721b1afd3c3730daadaf65ec3a8f68\", \"native_theme_unittests\": \"33fe00e923212c2fa05e9b7f6cf69bee1da70e97\", \"net_unittests\": \"c3c7c33481eff6a108d6f5cd9901603441e64282\", \"ozone_gl_unittests\": \"630406f8681d7636f99d2507fd6d5f3f95e61965\", \"ozone_unittests\": \"f385f687039530ad175b0252d2553597ba07bffe\", \"ozone_x11_unittests\": \"e11942a1f18afebc6364d8fc9bceb003ba568009\", \"pdf_unittests\": \"235c2718761eb813f2a153c5715d74e0ecfbbb29\", \"perfetto_unittests\": \"b81bfbf184b758956777d6ba0c0a27275cb78524\", \"ppapi_unittests\": \"edd226341ac2a2de63d2f7f0b5360d07a258df28\", \"printing_unittests\": \"1dbaf7072776cbb7dd7d0ea51116281bf1c6d936\", \"remoting_unittests\": \"feef7f7b63871917b04c7e6d4b78b59ec98c5256\", \"sandbox_linux_unittests\": \"21b06c9c7b08cea911c1bf30e9c607c30870ddc5\", \"service_manager_unittests\": \"61a162d29603df89973050e600dca8f88869f6bc\", \"services_unittests\": \"3113d777a6690525eea5b890560c40dfcfb53b27\", \"shell_dialogs_unittests\": \"9cac4bceba6b2aace3006f808d04127608b8efd4\", \"shell_encryption_unittests\": \"0b8fa6a1bb792ffa32660e1836de58a9beee6db4\", \"skia_unittests\": \"fab0e5dea0df513212ad43f56729b02f50c95ff4\", \"snapshot_unittests\": \"b4a51759ddded67521851f2feadbe70cf74dd3b1\", \"sql_unittests\": \"479e77157ff86ec988d194b749894c05ae50e28d\", \"storage_unittests\": \"cb96328f84927553620b119e0c81a4ae54de316f\", \"sync_integration_tests\": \"38e3bd32cd0351960a873650f1af09634ec04f94\", \"traffic_annotation_auditor_unittests\": \"2fc04a9cd51c06354038876399f6ecc0c517ec3b\", \"ui_base_unittests\": \"69474344fcb9b06b8c5a9d788809cfee159a1572\", \"ui_chromeos_unittests\": \"b6564d471c2ac8f74589dcaadedf328b94ccd95a\", \"ui_touch_selection_unittests\": \"dbfbbc867c4e8220a8ac9cf6ef67171b0b9ff110\", \"unit_tests\": \"e4ccc7601057170be20eb0b67563fc25bbdc488b\", \"url_unittests\": \"5849954e6f23a7a4c3d52ce47e26e8c6767bb5fc\", \"usage_time_limit_unittests\": \"039c872461e691d8dbd0db1530a9a8fdb507afd3\", \"views_unittests\": \"7b91936160aa506c551bf0087716109a5ef380c7\", \"viz_unittests\": \"13712af04f8129f7e663ff2091f346fefe1ed5c7\", \"wayland_client_tests\": \"2765444dc7120c536e84f0c91ffd12ecb144276b\", \"wm_unittests\": \"62343ac94bf6fc0cc9b70ff9470e7602263f2ee7\", \"wtf_unittests\": \"5bf22a8fa8dfe9cc919bb2f6809b59a2594ad35f\", \"zlib_unittests\": \"ef8b0de5cd2fda95af4bf6456b36efe722a7acbe\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_349e20fa0346c22afd9d85eafc166958d65f8d3eb174d2113a3f89c2e822dead_v2\", \"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_4637b11d2a56187e25d14badb4fa154a12143e816e25cb34f5fe38c5c81ebb47_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_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-201-1nl3\"], \"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\": [\"5746-91ff3bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615303007019261",
"status": "COMPLETED",
"status_changed_ts": "1615308631666937",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22923",
"builder:linux-chromeos-dbg",
"buildset:commit/git/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51b302114c168d8d7ccebc2f1f42e12564fd9cf1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018656194395296448",
"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/8853226132406077632/+/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:52305bd5643f4810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615308631748516",
"url": "https://ci.chromium.org/b/8853226132406077632",
"utcnow_ts": "1615308632990807"
}
}
[16:50:33.003] Invocation finished in 1h39m51.888604411s with status SUCCEEDED