[21:01:00.985] New invocation is queued and will start shortly
[21:01:02.162] Starting the invocation (attempt 1)
[21:01:02.214] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:01:02.214] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:01:02.214] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022348629113094112",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"11b24c51ea11cad829ead838f86746d55ca520df\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022348629113094112",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/11b24c51ea11cad829ead838f86746d55ca520df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b24c51ea11cad829ead838f86746d55ca520df",
"gitiles_ref:refs/heads/master"
]
}
[21:01:03.147] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611781262371034",
"id": "8856918566986102880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b24c51ea11cad829ead838f86746d55ca520df\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611781263000596",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22373",
"builder:linux-chromeos-dbg",
"buildset:commit/git/11b24c51ea11cad829ead838f86746d55ca520df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b24c51ea11cad829ead838f86746d55ca520df",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022348629113094112",
"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/8856918566986102880/+/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": "1611781263000755",
"url": "https://ci.chromium.org/b/8856918566986102880",
"utcnow_ts": "1611781263139375"
}
}
[21:01:03.147] Task URL: https://ci.chromium.org/b/8856918566986102880
[21:01:03.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:2:0) after 1m0s
[21:01:10.983] Received PubSub notification, asking Buildbucket for the build status
[21:01:11.154] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:03.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:2:0)
[21:02:03.199] Timer tick, asking Buildbucket for the build status
[21:02:03.411] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:03.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:4:0) after 1m0s
[21:03:03.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:4:0)
[21:03:03.430] Timer tick, asking Buildbucket for the build status
[21:03:03.649] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:03.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:5:0) after 1m0s
[21:04:03.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:5:0)
[21:04:03.720] Timer tick, asking Buildbucket for the build status
[21:04:04.254] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:04.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:6:0) after 1m0s
[21:05:04.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:6:0)
[21:05:04.265] Timer tick, asking Buildbucket for the build status
[21:05:04.778] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:04.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:7:0) after 1m0s
[21:06:04.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:7:0)
[21:06:04.869] Timer tick, asking Buildbucket for the build status
[21:06:05.016] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:05.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:8:0) after 1m0s
[21:07:05.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:8:0)
[21:07:05.048] Timer tick, asking Buildbucket for the build status
[21:07:05.381] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:05.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:9:0) after 1m0s
[21:08:05.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:9:0)
[21:08:05.377] Timer tick, asking Buildbucket for the build status
[21:08:05.682] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:05.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:10:0) after 1m0s
[21:09:05.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:10:0)
[21:09:05.713] Timer tick, asking Buildbucket for the build status
[21:09:05.778] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:05.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:11:0) after 1m0s
[21:10:05.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:11:0)
[21:10:05.843] Timer tick, asking Buildbucket for the build status
[21:10:06.085] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:06.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:12:0) after 1m0s
[21:11:06.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:12:0)
[21:11:06.084] Timer tick, asking Buildbucket for the build status
[21:11:06.252] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:06.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:13:0) after 1m0s
[21:12:06.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:13:0)
[21:12:06.251] Timer tick, asking Buildbucket for the build status
[21:12:06.314] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:06.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:14:0) after 1m0s
[21:13:06.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:14:0)
[21:13:06.357] Timer tick, asking Buildbucket for the build status
[21:13:06.790] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:06.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:15:0) after 1m0s
[21:14:06.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:15:0)
[21:14:06.847] Timer tick, asking Buildbucket for the build status
[21:14:06.955] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:06.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:16:0) after 1m0s
[21:15:07.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:16:0)
[21:15:07.043] Timer tick, asking Buildbucket for the build status
[21:15:07.127] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:07.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:17:0) after 1m0s
[21:16:07.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:17:0)
[21:16:07.236] Timer tick, asking Buildbucket for the build status
[21:16:07.358] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:18:0) after 1m0s
[21:17:07.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:18:0)
[21:17:07.435] Timer tick, asking Buildbucket for the build status
[21:17:07.638] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:19:0) after 1m0s
[21:18:07.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:19:0)
[21:18:07.687] Timer tick, asking Buildbucket for the build status
[21:18:07.801] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:07.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:20:0) after 1m0s
[21:19:07.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:20:0)
[21:19:07.861] Timer tick, asking Buildbucket for the build status
[21:19:07.916] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:07.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:21:0) after 1m0s
[21:20:08.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:21:0)
[21:20:08.020] Timer tick, asking Buildbucket for the build status
[21:20:08.222] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:08.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:22:0) after 1m0s
[21:21:08.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:22:0)
[21:21:08.261] Timer tick, asking Buildbucket for the build status
[21:21:08.642] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:08.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:23:0) after 1m0s
[21:22:08.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:23:0)
[21:22:08.666] Timer tick, asking Buildbucket for the build status
[21:22:08.997] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:08.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:24:0) after 1m0s
[21:23:09.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:24:0)
[21:23:09.032] Timer tick, asking Buildbucket for the build status
[21:23:09.460] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:09.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:25:0) after 1m0s
[21:24:09.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:25:0)
[21:24:09.500] Timer tick, asking Buildbucket for the build status
[21:24:09.760] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:09.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:26:0) after 1m0s
[21:25:09.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:26:0)
[21:25:09.780] Timer tick, asking Buildbucket for the build status
[21:25:09.859] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:09.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:27:0) after 1m0s
[21:26:09.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:27:0)
[21:26:09.879] Timer tick, asking Buildbucket for the build status
[21:26:10.192] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:28:0) after 1m0s
[21:27:10.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:28:0)
[21:27:10.180] Timer tick, asking Buildbucket for the build status
[21:27:10.240] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:10.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:29:0) after 1m0s
[21:28:10.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:29:0)
[21:28:10.267] Timer tick, asking Buildbucket for the build status
[21:28:10.356] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:10.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:30:0) after 1m0s
[21:29:10.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:30:0)
[21:29:10.398] Timer tick, asking Buildbucket for the build status
[21:29:10.490] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:10.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:31:0) after 1m0s
[21:30:10.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:31:0)
[21:30:10.514] Timer tick, asking Buildbucket for the build status
[21:30:10.583] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:10.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:32:0) after 1m0s
[21:31:10.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:32:0)
[21:31:10.812] Timer tick, asking Buildbucket for the build status
[21:31:10.987] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:10.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:33:0) after 1m0s
[21:32:11.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:33:0)
[21:32:11.087] Timer tick, asking Buildbucket for the build status
[21:32:11.216] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:11.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:34:0) after 1m0s
[21:33:11.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:34:0)
[21:33:11.339] Timer tick, asking Buildbucket for the build status
[21:33:11.413] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:11.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:35:0) after 1m0s
[21:34:11.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:35:0)
[21:34:11.779] Timer tick, asking Buildbucket for the build status
[21:34:11.855] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:11.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:36:0) after 1m0s
[21:35:11.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:36:0)
[21:35:11.968] Timer tick, asking Buildbucket for the build status
[21:35:12.602] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:12.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:37:0) after 1m0s
[21:36:12.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:37:0)
[21:36:12.717] Timer tick, asking Buildbucket for the build status
[21:36:12.821] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:38:0) after 1m0s
[21:37:12.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:38:0)
[21:37:12.926] Timer tick, asking Buildbucket for the build status
[21:37:13.001] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:13.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:39:0) after 1m0s
[21:38:13.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:39:0)
[21:38:13.140] Timer tick, asking Buildbucket for the build status
[21:38:13.636] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:13.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:40:0) after 1m0s
[21:39:13.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:40:0)
[21:39:13.691] Timer tick, asking Buildbucket for the build status
[21:39:13.768] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:13.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:41:0) after 1m0s
[21:40:13.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:41:0)
[21:40:13.860] Timer tick, asking Buildbucket for the build status
[21:40:13.960] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:13.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:42:0) after 1m0s
[21:41:14.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:42:0)
[21:41:14.059] Timer tick, asking Buildbucket for the build status
[21:41:14.174] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:14.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:43:0) after 1m0s
[21:42:14.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:43:0)
[21:42:14.177] Timer tick, asking Buildbucket for the build status
[21:42:14.235] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:14.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:44:0) after 1m0s
[21:43:14.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:44:0)
[21:43:14.294] Timer tick, asking Buildbucket for the build status
[21:43:14.360] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:14.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:45:0) after 1m0s
[21:44:14.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:45:0)
[21:44:14.408] Timer tick, asking Buildbucket for the build status
[21:44:14.516] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:14.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:46:0) after 1m0s
[21:45:14.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:46:0)
[21:45:14.510] Timer tick, asking Buildbucket for the build status
[21:45:14.637] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:14.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:47:0) after 1m0s
[21:46:14.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:47:0)
[21:46:14.841] Timer tick, asking Buildbucket for the build status
[21:46:15.248] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:15.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:48:0) after 1m0s
[21:47:15.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:48:0)
[21:47:15.420] Timer tick, asking Buildbucket for the build status
[21:47:15.638] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:15.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:49:0) after 1m0s
[21:48:15.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:49:0)
[21:48:15.809] Timer tick, asking Buildbucket for the build status
[21:48:15.877] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:15.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:50:0) after 1m0s
[21:49:15.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:50:0)
[21:49:15.966] Timer tick, asking Buildbucket for the build status
[21:49:16.329] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:16.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:51:0) after 1m0s
[21:50:16.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:51:0)
[21:50:16.429] Timer tick, asking Buildbucket for the build status
[21:50:16.565] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:16.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:52:0) after 1m0s
[21:51:16.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:52:0)
[21:51:16.627] Timer tick, asking Buildbucket for the build status
[21:51:16.780] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:16.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:53:0) after 1m0s
[21:52:16.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:53:0)
[21:52:16.829] Timer tick, asking Buildbucket for the build status
[21:52:16.950] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:16.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:54:0) after 1m0s
[21:53:17.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:54:0)
[21:53:17.450] Timer tick, asking Buildbucket for the build status
[21:53:17.564] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:17.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:55:0) after 1m0s
[21:54:17.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:55:0)
[21:54:17.669] Timer tick, asking Buildbucket for the build status
[21:54:17.733] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:17.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:56:0) after 1m0s
[21:55:17.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:56:0)
[21:55:17.906] Timer tick, asking Buildbucket for the build status
[21:55:18.134] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:18.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:57:0) after 1m0s
[21:56:18.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:57:0)
[21:56:18.197] Timer tick, asking Buildbucket for the build status
[21:56:18.422] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:18.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:58:0) after 1m0s
[21:57:18.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:58:0)
[21:57:18.462] Timer tick, asking Buildbucket for the build status
[21:57:18.689] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:18.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:59:0) after 1m0s
[21:58:18.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:59:0)
[21:58:18.739] Timer tick, asking Buildbucket for the build status
[21:58:19.259] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:19.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:60:0) after 1m0s
[21:59:19.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:60:0)
[21:59:19.359] Timer tick, asking Buildbucket for the build status
[21:59:19.475] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:19.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:61:0) after 1m0s
[22:00:19.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:61:0)
[22:00:19.549] Timer tick, asking Buildbucket for the build status
[22:00:19.733] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:19.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:62:0) after 1m0s
[22:01:19.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:62:0)
[22:01:19.882] Timer tick, asking Buildbucket for the build status
[22:01:20.513] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:20.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:63:0) after 1m0s
[22:02:20.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:63:0)
[22:02:20.617] Timer tick, asking Buildbucket for the build status
[22:02:21.000] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:21.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:64:0) after 1m0s
[22:03:21.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:64:0)
[22:03:21.092] Timer tick, asking Buildbucket for the build status
[22:03:21.319] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:21.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:65:0) after 1m0s
[22:04:21.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:65:0)
[22:04:21.452] Timer tick, asking Buildbucket for the build status
[22:04:21.724] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:21.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:66:0) after 1m0s
[22:05:21.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:66:0)
[22:05:21.749] Timer tick, asking Buildbucket for the build status
[22:05:21.851] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:21.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:67:0) after 1m0s
[22:06:21.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:67:0)
[22:06:21.906] Timer tick, asking Buildbucket for the build status
[22:06:22.677] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:22.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:68:0) after 1m0s
[22:07:22.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:68:0)
[22:07:22.746] Timer tick, asking Buildbucket for the build status
[22:07:22.848] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:22.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:69:0) after 1m0s
[22:08:22.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:69:0)
[22:08:22.854] Timer tick, asking Buildbucket for the build status
[22:08:22.933] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:22.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:70:0) after 1m0s
[22:09:22.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:70:0)
[22:09:22.951] Timer tick, asking Buildbucket for the build status
[22:09:23.317] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:23.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:71:0) after 1m0s
[22:10:23.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:71:0)
[22:10:23.512] Timer tick, asking Buildbucket for the build status
[22:10:23.619] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:23.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:72:0) after 1m0s
[22:11:23.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:72:0)
[22:11:23.662] Timer tick, asking Buildbucket for the build status
[22:11:23.853] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:23.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:73:0) after 1m0s
[22:12:23.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:73:0)
[22:12:23.897] Timer tick, asking Buildbucket for the build status
[22:12:24.031] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:24.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:74:0) after 1m0s
[22:13:24.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:74:0)
[22:13:24.076] Timer tick, asking Buildbucket for the build status
[22:13:24.362] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:24.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:75:0) after 1m0s
[22:14:24.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:75:0)
[22:14:24.389] Timer tick, asking Buildbucket for the build status
[22:14:24.533] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:24.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:76:0) after 1m0s
[22:15:24.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:76:0)
[22:15:24.591] Timer tick, asking Buildbucket for the build status
[22:15:25.014] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:25.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:77:0) after 1m0s
[22:16:25.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:77:0)
[22:16:25.018] Timer tick, asking Buildbucket for the build status
[22:16:25.124] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:25.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:78:0) after 1m0s
[22:17:25.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:78:0)
[22:17:25.120] Timer tick, asking Buildbucket for the build status
[22:17:25.308] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:25.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:79:0) after 1m0s
[22:18:25.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:79:0)
[22:18:25.309] Timer tick, asking Buildbucket for the build status
[22:18:25.642] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:25.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:80:0) after 1m0s
[22:19:25.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:80:0)
[22:19:25.764] Timer tick, asking Buildbucket for the build status
[22:19:25.912] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:25.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:81:0) after 1m0s
[22:20:25.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:81:0)
[22:20:25.904] Timer tick, asking Buildbucket for the build status
[22:20:26.733] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:26.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:82:0) after 1m0s
[22:21:27.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:82:0)
[22:21:27.008] Timer tick, asking Buildbucket for the build status
[22:21:28.166] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:28.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:83:0) after 1m0s
[22:22:28.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:83:0)
[22:22:28.217] Timer tick, asking Buildbucket for the build status
[22:22:28.418] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:28.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:84:0) after 1m0s
[22:23:28.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:84:0)
[22:23:28.474] Timer tick, asking Buildbucket for the build status
[22:23:28.874] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:28.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:85:0) after 1m0s
[22:24:28.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:85:0)
[22:24:28.875] Timer tick, asking Buildbucket for the build status
[22:24:29.003] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:86:0) after 1m0s
[22:25:29.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:86:0)
[22:25:29.061] Timer tick, asking Buildbucket for the build status
[22:25:29.510] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:29.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:87:0) after 1m0s
[22:26:29.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:87:0)
[22:26:29.543] Timer tick, asking Buildbucket for the build status
[22:26:29.931] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:29.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:88:0) after 1m0s
[22:27:30.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:88:0)
[22:27:30.067] Timer tick, asking Buildbucket for the build status
[22:27:30.146] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:30.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:89:0) after 1m0s
[22:28:30.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:89:0)
[22:28:30.264] Timer tick, asking Buildbucket for the build status
[22:28:30.413] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:30.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:90:0) after 1m0s
[22:29:30.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:90:0)
[22:29:30.444] Timer tick, asking Buildbucket for the build status
[22:29:30.612] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:30.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:91:0) after 1m0s
[22:30:30.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:91:0)
[22:30:30.802] Timer tick, asking Buildbucket for the build status
[22:30:30.912] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:30.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:92:0) after 1m0s
[22:31:31.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:92:0)
[22:31:31.207] Timer tick, asking Buildbucket for the build status
[22:31:31.432] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:31.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:93:0) after 1m0s
[22:32:31.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:93:0)
[22:32:31.674] Timer tick, asking Buildbucket for the build status
[22:32:31.857] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:31.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:94:0) after 1m0s
[22:33:31.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:94:0)
[22:33:31.916] Timer tick, asking Buildbucket for the build status
[22:33:32.032] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:32.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:95:0) after 1m0s
[22:34:32.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:95:0)
[22:34:32.660] Timer tick, asking Buildbucket for the build status
[22:34:32.759] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:32.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:96:0) after 1m0s
[22:35:33.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:96:0)
[22:35:33.217] Timer tick, asking Buildbucket for the build status
[22:35:33.612] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:33.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:97:0) after 1m0s
[22:36:33.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:97:0)
[22:36:33.633] Timer tick, asking Buildbucket for the build status
[22:36:34.267] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:34.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:98:0) after 1m0s
[22:37:34.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:98:0)
[22:37:34.310] Timer tick, asking Buildbucket for the build status
[22:37:34.564] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:34.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:99:0) after 1m0s
[22:38:34.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:99:0)
[22:38:34.756] Timer tick, asking Buildbucket for the build status
[22:38:34.963] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:34.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:100:0) after 1m0s
[22:39:34.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:100:0)
[22:39:34.972] Timer tick, asking Buildbucket for the build status
[22:39:35.120] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:101:0) after 1m0s
[22:40:35.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:101:0)
[22:40:35.181] Timer tick, asking Buildbucket for the build status
[22:40:37.359] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:37.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:102:0) after 1m0s
[22:41:37.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:102:0)
[22:41:37.407] Timer tick, asking Buildbucket for the build status
[22:41:37.885] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:37.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:103:0) after 1m0s
[22:42:37.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:103:0)
[22:42:37.919] Timer tick, asking Buildbucket for the build status
[22:42:38.125] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:38.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:104:0) after 1m0s
[22:43:38.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:104:0)
[22:43:38.223] Timer tick, asking Buildbucket for the build status
[22:43:38.637] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:38.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:105:0) after 1m0s
[22:44:38.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:105:0)
[22:44:38.702] Timer tick, asking Buildbucket for the build status
[22:44:38.917] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:38.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:106:0) after 1m0s
[22:45:38.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:106:0)
[22:45:38.924] Timer tick, asking Buildbucket for the build status
[22:45:39.112] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:39.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:107:0) after 1m0s
[22:46:39.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:107:0)
[22:46:39.147] Timer tick, asking Buildbucket for the build status
[22:46:39.528] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:39.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:108:0) after 1m0s
[22:47:39.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:108:0)
[22:47:39.516] Timer tick, asking Buildbucket for the build status
[22:47:39.841] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:39.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:109:0) after 1m0s
[22:48:39.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:109:0)
[22:48:39.885] Timer tick, asking Buildbucket for the build status
[22:48:40.176] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:40.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:110:0) after 1m0s
[22:49:40.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:110:0)
[22:49:40.217] Timer tick, asking Buildbucket for the build status
[22:49:40.338] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:40.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:111:0) after 1m0s
[22:50:40.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:111:0)
[22:50:40.379] Timer tick, asking Buildbucket for the build status
[22:50:40.868] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:40.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:112:0) after 1m0s
[22:51:40.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:112:0)
[22:51:40.975] Timer tick, asking Buildbucket for the build status
[22:51:41.268] Build 8856918566986102880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:41.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022348629113094112:113:0) after 1m0s
[22:52:16.155] Received PubSub notification, asking Buildbucket for the build status
[22:52:16.253] Build 8856918566986102880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:52:16.253] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611787933675567",
"created_by": "project:chromium",
"created_ts": "1611781262371034",
"id": "8856918566986102880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b24c51ea11cad829ead838f86746d55ca520df\"}}",
"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\": \"11b24c51ea11cad829ead838f86746d55ca520df\", \"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-197-j1hi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22373, \"got_angle_revision\": \"52afb6f17cbd13a290c2798b565067f47d47af86\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"11b24c51ea11cad829ead838f86746d55ca520df\", \"got_revision_cp\": \"refs/heads/master@{#847779}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"74ef1d1cf560c22c37a43f202a6275c8ed92ae97\", \"got_v8_revision_cp\": \"refs/heads/9.0.85@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"prev_got_revision\": \"81f3f8b91292cc0c5cbcf2149f35d0ae05431c54\", \"prev_got_revision_cp\": \"refs/heads/master@{#847313}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b24c51ea11cad829ead838f86746d55ca520df\", \"swarm_hashes_refs/heads/master(at){#847779}_without_patch\": {\"absl_hardening_tests\": \"ceae828fee1da4567f24962aee87b7cfb5122036\", \"accessibility_unittests\": \"b6f20ada1a163452c083ccbc39dcbbc2c2db913f\", \"angle_unittests\": \"e6bd69f7fa8aed3a6941a9f2fc9a53bd0d7f0c31\", \"app_list_unittests\": \"8d1b1e8fe6a45e20a43da6e78e2217d54c7e047a\", \"app_shell_unittests\": \"eb1993400add0150bf3b3dc302fda24cc74f8aa6\", \"ash_unittests\": \"86827f4a1d46dd5e5bfa827445737370288667e3\", \"aura_unittests\": \"6edcb3a1d8bfa80c6d4d08641fefe3d3ac68fcb1\", \"base_unittests\": \"be585a129f42bc11f0715d866c8badd59d5f4ed6\", \"base_util_unittests\": \"ba38c669ab9e40bc11f4eeed1163a7d06c6da9c4\", \"blink_common_unittests\": \"99f1571aadb80734a8291dd2d04626cb0e07a2c2\", \"blink_fuzzer_unittests\": \"4b89c15b3f0c069fb3d6cd8c66239a06573ec420\", \"blink_heap_unittests\": \"66d248e31c2b67f1e5486b4b5d6463f8ca8bd696\", \"blink_platform_unittests\": \"6ca0e735345de8f6d48ddcbcd2d72cbeaf7b77a6\", \"blink_unittests\": \"53439930800f9ea175e40de55200b99a4b93a498\", \"boringssl_crypto_tests\": \"432ba9522aaab2e0705c69a7e7873fc097cc9794\", \"boringssl_ssl_tests\": \"737a900f849885a83bd6e9ff3a562e67141851e1\", \"browser_tests\": \"741997ee6c2d70cb19cdc2e7f55fbbadc20f3e48\", \"capture_unittests\": \"ea29878d3ddc425ecf11b0e35957f6eb129be907\", \"cast_unittests\": \"97e1fecafa374d3a08d305a790e30b7cc61dc448\", \"cc_unittests\": \"255909e2cc89d553f5bd18037bf45f4eb54f7a46\", \"chrome_app_unittests\": \"7ea5c652a6a32f3d3cbb7d4aec91034d97d64293\", \"chromedriver_unittests\": \"4a80490f43d9644636bdb090a7adb43dacb3408d\", \"chromeos_components_unittests\": \"92ebb83983df3c27e3b5dccde6a00434c8188f21\", \"chromeos_unittests\": \"3cfb5b3858155637b17ac742f3356396e58bbc5f\", \"components_browsertests\": \"14b2d814d40ef50bdfccece847b1ee197a76438f\", \"components_unittests\": \"88a6fdb227025cf9effce556e6cfbbd761a2f288\", \"compositor_unittests\": \"a517fd649c89ea16bcba37d49f21aa19172fa1fe\", \"content_browsertests\": \"82d1eb745710dfd84238fa4ca0886a7e9ce0601c\", \"content_unittests\": \"2ce06c239e3f6729a069772dc56440ed63a03e01\", \"crashpad_tests\": \"ab0c3257419d006c69271acb8b0245636241bfe7\", \"crypto_unittests\": \"303393894023e0870393c872a7e864f6677ecb9e\", \"dbus_unittests\": \"937ee7cf73be70a20899270ea7e0d5a4bcf1b627\", \"device_unittests\": \"a0758601c1b98d33763726234c35d7fec53d7cc9\", \"display_unittests\": \"d9c92a669fd41e03d5ec64347c189207f9f189d7\", \"events_unittests\": \"3f0b5f52ae945d7f4da6bf11f552491c53fac4c2\", \"exo_unittests\": \"07069f778a105fe1fe2f2c5fbad3ed747ac2edc5\", \"extensions_browsertests\": \"73aadd0496e88fb2c10bcad854b1540c235aec55\", \"extensions_unittests\": \"3bf5ec0cd66b83dd35fef8b727ca1ed8ac554d33\", \"filesystem_service_unittests\": \"a7545250bf56e71abc6055a76dc70af2f8fdd5da\", \"gcm_unit_tests\": \"e7e74d99ba695c66d0c8c59caeb48a9beebcb658\", \"gfx_unittests\": \"ecf1e3e3dc44d139e9b40730196db77008d4ecea\", \"gin_unittests\": \"59ba084038957a532dc683fca8e0092591cc54b2\", \"gl_unittests_ozone\": \"f57c29c8f800083c481e78e20b905732297da925\", \"google_apis_unittests\": \"3c82a0a1ae0527d1dc310a1824b95fd0f9a35164\", \"gpu_unittests\": \"9a87af99f16c5d15cf77eec91dbd78e755d38d21\", \"gwp_asan_unittests\": \"99fb2323848eaaf14423c528fd4124169dcfdbb9\", \"interactive_ui_tests\": \"3ddd165a742be2c249118db2b55797cde6f9b626\", \"ipc_tests\": \"d3aec1ca3178ff6028c3a17af0edfd37c1bd9d52\", \"jingle_unittests\": \"4321e970c4ec8d292ef43a6c0c3d1c54133dbcad\", \"keyboard_unittests\": \"e8ae8f0e0285e2ff0a9b7fad50ca1a4e0d2debe6\", \"latency_unittests\": \"db3d9c50f1b7a4256450088330c73aa17a7a4c22\", \"libjingle_xmpp_unittests\": \"695c063e27fea0e557724df67a2339d33ff371ee\", \"liburlpattern_unittests\": \"a0cd9b91d99c9db5612595f12696053577ebd6ef\", \"media_blink_unittests\": \"9dc4a98d3515335a78a774c314cc1f3633bf3a98\", \"media_unittests\": \"bf199b72fdab0411e121f74a6a2c01399a49d085\", \"message_center_unittests\": \"ea6d5d0e8da7d12e2c449cab08dd495d2b238a37\", \"midi_unittests\": \"bc15ba142e3478f53fdb872db8dda9aa7372d084\", \"mojo_core_unittests\": \"7b817bdfe5ff1900f7c3d4ccf2288525453540c4\", \"mojo_unittests\": \"35a0a0f9639a5fcdb42ff344353466bf3360b8ea\", \"nacl_helper_nonsfi_unittests\": \"17255b9cd2efd985b3cfb513c0da06dd1f266832\", \"nacl_loader_unittests\": \"971315c1e5fb03734bcec497ba0feaa64fc9558c\", \"native_theme_unittests\": \"4c8d9a2b848858916885383a2a47d8f2b3120812\", \"net_unittests\": \"540f58978e896942c521e5be1f2ccb556bbdf2f5\", \"ozone_gl_unittests\": \"f463b3eef30bee31fa2b9b60b03a6b126e272881\", \"ozone_unittests\": \"28fce0f3d7f658933799d3080f7fe3d5f2585129\", \"ozone_x11_unittests\": \"fac0f9b2597b028bd09f2c23cf546c00892f063c\", \"pdf_unittests\": \"64f1b27833554b422226b666e7fe5fd6a651c704\", \"perfetto_unittests\": \"e3e1d374c1e77402bb2a83b6a283255b2440ffe8\", \"ppapi_unittests\": \"4d09c69626f0a79fa3006f29cd055d1aec5defc4\", \"printing_unittests\": \"f76ee1d21d74253b4091e880dc0244e8836528c1\", \"remoting_unittests\": \"142f86eec5cf47c41f9ae566bbf9991f2dacd79b\", \"sandbox_linux_unittests\": \"a58c6baa8d8fa92f4d74abdc617a53db27f41948\", \"service_manager_unittests\": \"d1108ebc28373d94b96da0218076fba4c08d5c00\", \"services_unittests\": \"9f380a89b3fc3930a785dacc492c4b1eaeb21fac\", \"shell_dialogs_unittests\": \"89c6f87c455ff23ba2b2dc559f97947d0710c6cf\", \"shell_encryption_unittests\": \"cde73972736fa398fdf4a1316cae1427c0dd0826\", \"skia_unittests\": \"298e65d59b8d17bcd273a5c4fc0340f142edb470\", \"snapshot_unittests\": \"db35d510f2b8c621d29a4ab311271f88b1558fa8\", \"sql_unittests\": \"3c75248a377e23db22c404024c36a18f52f53db3\", \"storage_unittests\": \"873adb52db05c7a8f4d2c99a03eccf4c79c2af62\", \"sync_integration_tests\": \"5577f27c3d5c7e45ae7120f82937456944663d99\", \"traffic_annotation_auditor_unittests\": \"c0787dd5f4c5d29496c14957162a39e984a73c69\", \"ui_base_unittests\": \"dd6e4eae0385f10944a3e784dd777a8c7f7f9504\", \"ui_chromeos_unittests\": \"9b644eaafe0d6da31c68221cb6ed09002dd7f138\", \"ui_touch_selection_unittests\": \"6e7130c3e2e542a63d0cc17c9afd2de8eae02dce\", \"unit_tests\": \"f9f3a351a1f0b80a4ee45fcbe9d078855c97af9c\", \"url_unittests\": \"2e8d22ec6dbeee48a1a4c61f77296a2498675a01\", \"usage_time_limit_unittests\": \"624e518288ffefb47d94e490cee16b236c95d262\", \"views_unittests\": \"4a53c986b1d28ca8cf51b53623b35ffc61abf9c4\", \"viz_unittests\": \"670f1e1d70adea4e59976dc21ec59df4670cd9f6\", \"wm_unittests\": \"c825bc91849f516b9699f7d20937185f78b3040a\", \"wtf_unittests\": \"c7b005dd2e5fea33810c0a4bd6e690145e1115c8\", \"zlib_unittests\": \"a799775f0322b7c79e7f25009842d7fb03fc0354\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02c50c25350278ea3e2feb85c748c248af9e0ccc93852ce717c8651b544f7a96_v2\", \"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_4e62a2152674f2c3254d7105a557302458cce07b755aab1e21dcbd6c7ddeb832_v2\", \"builder_678d8b556ed6d2ea4aa43b80debb7b547a1d6d4433ddff28e80ab170912c760d_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-197-j1hi\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611781266652987",
"status": "COMPLETED",
"status_changed_ts": "1611787934587733",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22373",
"builder:linux-chromeos-dbg",
"buildset:commit/git/11b24c51ea11cad829ead838f86746d55ca520df",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b24c51ea11cad829ead838f86746d55ca520df",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022348629113094112",
"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/8856918566986102880/+/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:515e77d86a077910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611787934615902",
"url": "https://ci.chromium.org/b/8856918566986102880",
"utcnow_ts": "1611787936244619"
}
}
[22:52:16.253] Invocation finished in 1h51m15.294456232s with status SUCCEEDED