[22:28:28.185] New invocation is queued and will start shortly
[22:28:29.573] Starting the invocation (attempt 1)
[22:28:29.667] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:28:29.667] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:28:29.667] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031131032757786080",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"259e147f157ca259d9d29abcda624aeac3981343\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9031131032757786080",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/259e147f157ca259d9d29abcda624aeac3981343",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/259e147f157ca259d9d29abcda624aeac3981343",
"gitiles_ref:refs/heads/master"
]
}
[22:28:30.274] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603405709759597",
"id": "8865700970442272704",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"259e147f157ca259d9d29abcda624aeac3981343\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603405710155442",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20969",
"builder:linux-chromeos-dbg",
"buildset:commit/git/259e147f157ca259d9d29abcda624aeac3981343",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/259e147f157ca259d9d29abcda624aeac3981343",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031131032757786080",
"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/8865700970442272704/+/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": "1603405710155547",
"url": "https://ci.chromium.org/b/8865700970442272704",
"utcnow_ts": "1603405710267269"
}
}
[22:28:30.274] Task URL: https://ci.chromium.org/b/8865700970442272704
[22:28:30.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:2:0) after 1m0s
[22:29:30.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:2:0)
[22:29:30.419] Timer tick, asking Buildbucket for the build status
[22:29:31.033] Build 8865700970442272704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:3:0) after 1m0s
[22:30:31.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:3:0)
[22:30:31.216] Timer tick, asking Buildbucket for the build status
[22:30:31.294] Build 8865700970442272704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:31.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:4:0) after 1m0s
[22:31:31.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:4:0)
[22:31:31.384] Timer tick, asking Buildbucket for the build status
[22:31:31.451] Build 8865700970442272704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:31.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:5:0) after 1m0s
[22:31:54.997] Received PubSub notification, asking Buildbucket for the build status
[22:31:55.622] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:31.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:5:0)
[22:32:31.542] Timer tick, asking Buildbucket for the build status
[22:32:31.658] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:31.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:7:0) after 1m0s
[22:33:31.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:7:0)
[22:33:31.713] Timer tick, asking Buildbucket for the build status
[22:33:33.693] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:33.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:8:0) after 1m0s
[22:34:33.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:8:0)
[22:34:33.852] Timer tick, asking Buildbucket for the build status
[22:34:33.917] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:33.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:9:0) after 1m0s
[22:35:33.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:9:0)
[22:35:33.973] Timer tick, asking Buildbucket for the build status
[22:35:34.121] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:34.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:10:0) after 1m0s
[22:36:34.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:10:0)
[22:36:34.127] Timer tick, asking Buildbucket for the build status
[22:36:34.228] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:34.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:11:0) after 1m0s
[22:37:34.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:11:0)
[22:37:34.303] Timer tick, asking Buildbucket for the build status
[22:37:34.452] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:34.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:12:0) after 1m0s
[22:38:34.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:12:0)
[22:38:34.456] Timer tick, asking Buildbucket for the build status
[22:38:34.590] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:34.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:13:0) after 1m0s
[22:39:34.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:13:0)
[22:39:34.639] Timer tick, asking Buildbucket for the build status
[22:39:34.758] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:34.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:14:0) after 1m0s
[22:40:34.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:14:0)
[22:40:34.789] Timer tick, asking Buildbucket for the build status
[22:40:34.949] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:34.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:15:0) after 1m0s
[22:41:35.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:15:0)
[22:41:35.036] Timer tick, asking Buildbucket for the build status
[22:41:35.114] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:35.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:16:0) after 1m0s
[22:42:35.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:16:0)
[22:42:35.117] Timer tick, asking Buildbucket for the build status
[22:42:35.175] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:35.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:17:0) after 1m0s
[22:43:35.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:17:0)
[22:43:35.241] Timer tick, asking Buildbucket for the build status
[22:43:35.604] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:35.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:18:0) after 1m0s
[22:44:35.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:18:0)
[22:44:35.625] Timer tick, asking Buildbucket for the build status
[22:44:37.977] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:37.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:19:0) after 1m0s
[22:45:37.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:19:0)
[22:45:37.977] Timer tick, asking Buildbucket for the build status
[22:45:38.072] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:38.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:20:0) after 1m0s
[22:46:38.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:20:0)
[22:46:38.099] Timer tick, asking Buildbucket for the build status
[22:46:38.217] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:38.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:21:0) after 1m0s
[22:47:38.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:21:0)
[22:47:38.261] Timer tick, asking Buildbucket for the build status
[22:47:38.333] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:38.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:22:0) after 1m0s
[22:48:38.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:22:0)
[22:48:38.375] Timer tick, asking Buildbucket for the build status
[22:48:38.473] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:23:0) after 1m0s
[22:49:38.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:23:0)
[22:49:38.501] Timer tick, asking Buildbucket for the build status
[22:49:40.733] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:40.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:24:0) after 1m0s
[22:50:40.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:24:0)
[22:50:40.808] Timer tick, asking Buildbucket for the build status
[22:50:41.075] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:41.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:25:0) after 1m0s
[22:51:41.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:25:0)
[22:51:41.112] Timer tick, asking Buildbucket for the build status
[22:51:41.190] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:41.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:26:0) after 1m0s
[22:52:41.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:26:0)
[22:52:41.322] Timer tick, asking Buildbucket for the build status
[22:52:41.396] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:41.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:27:0) after 1m0s
[22:53:41.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:27:0)
[22:53:41.536] Timer tick, asking Buildbucket for the build status
[22:53:41.776] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:41.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:28:0) after 1m0s
[22:54:41.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:28:0)
[22:54:41.785] Timer tick, asking Buildbucket for the build status
[22:54:41.867] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:41.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:29:0) after 1m0s
[22:55:41.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:29:0)
[22:55:41.972] Timer tick, asking Buildbucket for the build status
[22:55:42.853] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:42.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:30:0) after 1m0s
[22:56:42.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:30:0)
[22:56:42.863] Timer tick, asking Buildbucket for the build status
[22:56:42.945] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:42.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:31:0) after 1m0s
[22:57:43.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:31:0)
[22:57:43.000] Timer tick, asking Buildbucket for the build status
[22:57:43.065] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:43.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:32:0) after 1m0s
[22:58:43.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:32:0)
[22:58:43.191] Timer tick, asking Buildbucket for the build status
[22:58:43.353] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:43.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:33:0) after 1m0s
[22:59:43.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:33:0)
[22:59:43.383] Timer tick, asking Buildbucket for the build status
[22:59:43.454] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:43.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:34:0) after 1m0s
[23:00:43.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:34:0)
[23:00:43.560] Timer tick, asking Buildbucket for the build status
[23:00:43.656] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:43.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:35:0) after 1m0s
[23:01:43.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:35:0)
[23:01:43.775] Timer tick, asking Buildbucket for the build status
[23:01:43.900] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:43.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:36:0) after 1m0s
[23:02:43.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:36:0)
[23:02:43.914] Timer tick, asking Buildbucket for the build status
[23:02:44.000] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:44.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:37:0) after 1m0s
[23:03:44.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:37:0)
[23:03:44.049] Timer tick, asking Buildbucket for the build status
[23:03:44.158] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:44.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:38:0) after 1m0s
[23:04:44.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:38:0)
[23:04:44.223] Timer tick, asking Buildbucket for the build status
[23:04:44.505] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:39:0) after 1m0s
[23:05:44.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:39:0)
[23:05:44.565] Timer tick, asking Buildbucket for the build status
[23:05:44.898] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:44.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:40:0) after 1m0s
[23:06:44.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:40:0)
[23:06:44.951] Timer tick, asking Buildbucket for the build status
[23:06:45.075] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:41:0) after 1m0s
[23:07:45.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:41:0)
[23:07:45.106] Timer tick, asking Buildbucket for the build status
[23:07:45.378] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:45.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:42:0) after 1m0s
[23:08:45.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:42:0)
[23:08:45.412] Timer tick, asking Buildbucket for the build status
[23:08:45.567] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:45.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:43:0) after 1m0s
[23:09:45.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:43:0)
[23:09:45.613] Timer tick, asking Buildbucket for the build status
[23:09:45.790] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:45.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:44:0) after 1m0s
[23:10:45.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:44:0)
[23:10:45.806] Timer tick, asking Buildbucket for the build status
[23:10:45.871] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:45.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:45:0) after 1m0s
[23:11:45.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:45:0)
[23:11:45.923] Timer tick, asking Buildbucket for the build status
[23:11:45.999] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:45.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:46:0) after 1m0s
[23:12:46.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:46:0)
[23:12:46.032] Timer tick, asking Buildbucket for the build status
[23:12:46.396] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:46.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:47:0) after 1m0s
[23:13:46.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:47:0)
[23:13:46.429] Timer tick, asking Buildbucket for the build status
[23:13:47.494] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:47.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:48:0) after 1m0s
[23:14:47.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:48:0)
[23:14:47.521] Timer tick, asking Buildbucket for the build status
[23:14:47.918] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:47.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:49:0) after 1m0s
[23:15:47.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:49:0)
[23:15:47.968] Timer tick, asking Buildbucket for the build status
[23:15:48.080] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:48.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:50:0) after 1m0s
[23:16:48.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:50:0)
[23:16:48.107] Timer tick, asking Buildbucket for the build status
[23:16:48.213] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:48.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:51:0) after 1m0s
[23:17:48.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:51:0)
[23:17:48.270] Timer tick, asking Buildbucket for the build status
[23:17:48.381] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:48.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:52:0) after 1m0s
[23:18:48.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:52:0)
[23:18:48.409] Timer tick, asking Buildbucket for the build status
[23:18:49.033] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:49.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:53:0) after 1m0s
[23:19:49.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:53:0)
[23:19:49.082] Timer tick, asking Buildbucket for the build status
[23:19:49.192] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:49.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:54:0) after 1m0s
[23:20:49.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:54:0)
[23:20:49.218] Timer tick, asking Buildbucket for the build status
[23:20:49.359] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:49.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:55:0) after 1m0s
[23:21:49.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:55:0)
[23:21:49.393] Timer tick, asking Buildbucket for the build status
[23:21:49.506] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:49.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:56:0) after 1m0s
[23:22:49.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:56:0)
[23:22:49.535] Timer tick, asking Buildbucket for the build status
[23:22:49.621] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:49.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:57:0) after 1m0s
[23:23:49.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:57:0)
[23:23:49.637] Timer tick, asking Buildbucket for the build status
[23:23:49.773] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:49.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:58:0) after 1m0s
[23:24:49.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:58:0)
[23:24:49.835] Timer tick, asking Buildbucket for the build status
[23:24:49.908] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:49.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:59:0) after 1m0s
[23:25:49.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:59:0)
[23:25:49.940] Timer tick, asking Buildbucket for the build status
[23:25:50.119] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:60:0) after 1m0s
[23:26:50.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:60:0)
[23:26:50.176] Timer tick, asking Buildbucket for the build status
[23:26:50.300] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:50.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:61:0) after 1m0s
[23:27:50.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:61:0)
[23:27:50.329] Timer tick, asking Buildbucket for the build status
[23:27:50.696] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:50.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:62:0) after 1m0s
[23:28:50.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:62:0)
[23:28:50.759] Timer tick, asking Buildbucket for the build status
[23:28:50.961] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:50.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:63:0) after 1m0s
[23:29:51.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:63:0)
[23:29:51.009] Timer tick, asking Buildbucket for the build status
[23:29:51.362] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:51.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:64:0) after 1m0s
[23:30:51.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:64:0)
[23:30:51.425] Timer tick, asking Buildbucket for the build status
[23:30:52.029] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:52.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:65:0) after 1m0s
[23:31:52.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:65:0)
[23:31:52.113] Timer tick, asking Buildbucket for the build status
[23:31:52.262] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:52.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:66:0) after 1m0s
[23:32:52.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:66:0)
[23:32:52.365] Timer tick, asking Buildbucket for the build status
[23:32:52.488] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:52.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:67:0) after 1m0s
[23:33:52.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:67:0)
[23:33:52.513] Timer tick, asking Buildbucket for the build status
[23:33:52.638] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:52.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:68:0) after 1m0s
[23:34:52.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:68:0)
[23:34:52.788] Timer tick, asking Buildbucket for the build status
[23:34:52.924] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:52.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:69:0) after 1m0s
[23:35:52.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:69:0)
[23:35:52.977] Timer tick, asking Buildbucket for the build status
[23:35:53.054] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:53.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:70:0) after 1m0s
[23:36:53.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:70:0)
[23:36:53.094] Timer tick, asking Buildbucket for the build status
[23:36:53.198] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:53.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:71:0) after 1m0s
[23:37:53.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:71:0)
[23:37:53.334] Timer tick, asking Buildbucket for the build status
[23:37:53.508] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:53.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:72:0) after 1m0s
[23:38:53.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:72:0)
[23:38:53.650] Timer tick, asking Buildbucket for the build status
[23:38:53.803] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:53.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:73:0) after 1m0s
[23:39:53.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:73:0)
[23:39:53.889] Timer tick, asking Buildbucket for the build status
[23:39:53.960] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:53.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:74:0) after 1m0s
[23:40:54.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:74:0)
[23:40:54.219] Timer tick, asking Buildbucket for the build status
[23:40:54.293] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:54.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:75:0) after 1m0s
[23:41:54.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:75:0)
[23:41:54.360] Timer tick, asking Buildbucket for the build status
[23:41:54.703] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:54.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:76:0) after 1m0s
[23:42:54.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:76:0)
[23:42:54.838] Timer tick, asking Buildbucket for the build status
[23:42:54.920] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:54.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:77:0) after 1m0s
[23:43:55.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:77:0)
[23:43:55.039] Timer tick, asking Buildbucket for the build status
[23:43:55.179] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:55.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:78:0) after 1m0s
[23:44:55.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:78:0)
[23:44:55.314] Timer tick, asking Buildbucket for the build status
[23:44:55.635] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:79:0) after 1m0s
[23:45:55.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:79:0)
[23:45:55.672] Timer tick, asking Buildbucket for the build status
[23:45:55.735] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:55.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:80:0) after 1m0s
[23:46:55.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:80:0)
[23:46:55.833] Timer tick, asking Buildbucket for the build status
[23:46:55.909] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:55.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:81:0) after 1m0s
[23:47:55.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:81:0)
[23:47:55.949] Timer tick, asking Buildbucket for the build status
[23:47:56.029] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:56.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:82:0) after 1m0s
[23:48:56.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:82:0)
[23:48:56.095] Timer tick, asking Buildbucket for the build status
[23:48:56.209] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:56.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:83:0) after 1m0s
[23:49:56.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:83:0)
[23:49:56.276] Timer tick, asking Buildbucket for the build status
[23:49:56.418] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:56.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:84:0) after 1m0s
[23:50:56.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:84:0)
[23:50:56.447] Timer tick, asking Buildbucket for the build status
[23:50:56.564] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:56.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:85:0) after 1m0s
[23:51:56.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:85:0)
[23:51:56.603] Timer tick, asking Buildbucket for the build status
[23:51:57.387] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:57.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:86:0) after 1m0s
[23:52:57.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:86:0)
[23:52:57.518] Timer tick, asking Buildbucket for the build status
[23:52:57.591] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:57.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:87:0) after 1m0s
[23:53:57.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:87:0)
[23:53:57.672] Timer tick, asking Buildbucket for the build status
[23:53:57.775] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:57.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:88:0) after 1m0s
[23:54:57.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:88:0)
[23:54:57.869] Timer tick, asking Buildbucket for the build status
[23:54:57.946] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:57.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:89:0) after 1m0s
[23:55:58.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:89:0)
[23:55:58.002] Timer tick, asking Buildbucket for the build status
[23:55:58.412] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:58.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:90:0) after 1m0s
[23:56:58.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:90:0)
[23:56:58.444] Timer tick, asking Buildbucket for the build status
[23:56:58.736] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:91:0) after 1m0s
[23:57:58.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:91:0)
[23:57:58.848] Timer tick, asking Buildbucket for the build status
[23:57:58.934] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:58.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:92:0) after 1m0s
[23:58:58.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:92:0)
[23:58:58.937] Timer tick, asking Buildbucket for the build status
[23:58:59.057] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:93:0) after 1m0s
[23:59:59.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:93:0)
[23:59:59.087] Timer tick, asking Buildbucket for the build status
[23:59:59.278] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:59.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:94:0) after 1m0s
[00:00:59.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:94:0)
[00:00:59.308] Timer tick, asking Buildbucket for the build status
[00:00:59.398] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:59.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:95:0) after 1m0s
[00:01:59.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:95:0)
[00:01:59.632] Timer tick, asking Buildbucket for the build status
[00:01:59.715] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:59.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:96:0) after 1m0s
[00:03:00.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:96:0)
[00:03:00.079] Timer tick, asking Buildbucket for the build status
[00:03:01.036] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:01.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:97:0) after 1m0s
[00:04:01.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:97:0)
[00:04:01.210] Timer tick, asking Buildbucket for the build status
[00:04:01.353] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:01.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:98:0) after 1m0s
[00:05:01.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:98:0)
[00:05:01.468] Timer tick, asking Buildbucket for the build status
[00:05:01.672] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:01.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:99:0) after 1m0s
[00:06:02.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:99:0)
[00:06:02.069] Timer tick, asking Buildbucket for the build status
[00:06:02.178] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:02.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:100:0) after 1m0s
[00:07:02.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:100:0)
[00:07:02.459] Timer tick, asking Buildbucket for the build status
[00:07:02.617] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:02.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:101:0) after 1m0s
[00:08:02.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:101:0)
[00:08:02.622] Timer tick, asking Buildbucket for the build status
[00:08:02.708] Build 8865700970442272704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:02.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031131032757786080:102:0) after 1m0s
[00:08:45.070] Received PubSub notification, asking Buildbucket for the build status
[00:08:45.521] Build 8865700970442272704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:08:45.521] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603411724136233",
"created_by": "project:chromium",
"created_ts": "1603405709759597",
"id": "8865700970442272704",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"259e147f157ca259d9d29abcda624aeac3981343\"}}",
"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\": \"259e147f157ca259d9d29abcda624aeac3981343\", \"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-9-csso\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 20969, \"got_angle_revision\": \"9c8da02650bc3c3f0e1add512a3ee8ca7ef67e96\", \"got_dawn_revision\": \"2ce1d927e98a654bb9b7137a07a7ccf5dbb5f4df\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"259e147f157ca259d9d29abcda624aeac3981343\", \"got_revision_cp\": \"refs/heads/master@{#820039}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"d9ccb94fc2738d2c7bec626c287518c5e3ad14e9\", \"got_v8_revision_cp\": \"refs/heads/8.8.149@{#1}\", \"got_webrtc_revision\": \"7bc3356fbcb9b95ea2c810ae2f3152930d54577b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32449}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"259e147f157ca259d9d29abcda624aeac3981343\", \"swarm_hashes_refs/heads/master(at){#820039}_without_patch\": {\"absl_hardening_tests\": \"b62e82dee335e0141faa2398e07868c5b4563af9\", \"accessibility_unittests\": \"166c644b761313caa5d73e452fae6b7278af8c0a\", \"app_list_unittests\": \"fc11a9d83092a6a234f15fed253b056e79af613e\", \"app_shell_unittests\": \"632997bba9cbc73699c5095d0a992200f89706cc\", \"ash_unittests\": \"539671b84baec05a48b117419481ae64387cb022\", \"aura_unittests\": \"5247d183ef255a4420c4959295e3a6583babac52\", \"base_unittests\": \"771f25873471194b7aeec5f70edbbde27a8872f3\", \"base_util_unittests\": \"b0e898c72da26a1a1c69bfaad1a1510889f39341\", \"blink_common_unittests\": \"a0fdac056a9aeb095d21c1502574558d376bd98a\", \"blink_fuzzer_unittests\": \"18516a24c630e776577fb8db88e6c8d668ec5c5b\", \"blink_heap_unittests\": \"586e999d512d7e7184b03609b6420fdb870d78a1\", \"blink_platform_unittests\": \"c0677aeb736c5f5aefd39edac17cc4e640031310\", \"blink_unittests\": \"05b4574f659e7edde72fc30089f9582f18b68228\", \"boringssl_crypto_tests\": \"9e3a8582f123d83beec9850595d5aa0b63ddfc7d\", \"boringssl_ssl_tests\": \"52c7287f869d4ba3c7fb6cc22786f74ace208f0f\", \"browser_tests\": \"4ccba2bed782ad5cdd9723ea020ed40b8c0eeb36\", \"capture_unittests\": \"9a4e457082ae846b9143549f85ff9fd18fa39c3a\", \"cast_unittests\": \"3f8e9445e79beb4ab3ee917c076f93e1704d9647\", \"cc_unittests\": \"5e1bffec0359a866272a22639e3997405052e40c\", \"chrome_app_unittests\": \"5a34301441689c884c57abf854b6a1a104ad56a5\", \"chromedriver_unittests\": \"7d85257b82944ed685fc28bd6155c8beee55c005\", \"chromeos_components_unittests\": \"498e1333d434bd87898defb7a11c24fdd9d2c024\", \"chromeos_unittests\": \"45104a855636cd7bd6a205fb61deff5504c4d41d\", \"components_browsertests\": \"65d2d55b369ab078517c6304542853e1c7161cb4\", \"components_unittests\": \"268c4f9355fe8c20beca662fd7682d49819c4d49\", \"compositor_unittests\": \"a2ff2e00a49166857ce53e1a19dc61d0671cc94f\", \"content_browsertests\": \"19269d8aed0762c24faa4764df74076fc4cd9f96\", \"content_unittests\": \"a1299a1656dc779eadedba320ec3157120f3891a\", \"crypto_unittests\": \"0fa773edb10cc136ab282d9641f145bda17b9e30\", \"dbus_unittests\": \"2debb0d23a1cf56f3b9409031619884fe69c862e\", \"device_unittests\": \"9ff906492266535e39003d0b629368f0438f0b9c\", \"display_unittests\": \"1cfafc8dd29c07c5ed7af9519fa24d7a7726eca5\", \"events_unittests\": \"af515a9c50801ecdf56ba0fd6e52ab2ad7627ba3\", \"exo_unittests\": \"7d3fcb69b280693fc4e51b4474ae6071aec2901a\", \"extensions_browsertests\": \"cd188e6d44eeb86ab8601f56f086de352730d609\", \"extensions_unittests\": \"1328f3bbd39c3a3a254e580b749a3b54a3683d15\", \"filesystem_service_unittests\": \"a19a7deb99237c88358280bc2921ab0dbe727616\", \"gcm_unit_tests\": \"128bfb6667799d27a44199c2e05a2996c9e1ff61\", \"gfx_unittests\": \"0bc34b3133a0f61c639656c519a95ffa8047c561\", \"gin_unittests\": \"8559bf42f806fdf84f94b3e391003dc45ad04f8f\", \"gl_unittests_ozone\": \"c389846bc8297df0e3fed54de34cc68c03ca0d76\", \"google_apis_unittests\": \"ebf868c6df841d774789e06d594d13fef2291ef0\", \"gpu_unittests\": \"1ac51bc4987383ac6df0ed0ac6c9ea8de4dfb796\", \"gwp_asan_unittests\": \"ec731980237baa8ccedf9b957a8e6328c3af849e\", \"interactive_ui_tests\": \"d4a45de01e353af923379bce5c1787af3aed18e7\", \"ipc_tests\": \"e9c72051ef2c456e081fd75d1bb5e3d2c78521b6\", \"jingle_unittests\": \"938b065b7e4ca282245bcc53297c723b8440a526\", \"keyboard_unittests\": \"34e88c85fa9a48b3179590057a54e9511ba55b0f\", \"latency_unittests\": \"dccb35aa3cbbd66a7977e58a1cd4effd7f853180\", \"libjingle_xmpp_unittests\": \"75833452d5a87d13a2e1e28ea33f6384ca330f8d\", \"media_blink_unittests\": \"efc9713d659ccba223a1bd5c9e5fbacb8a8c66ac\", \"media_unittests\": \"45c35fc48f9bbdaa47a0c572433f689e8a43e6cc\", \"message_center_unittests\": \"7bc17f4099bb79d6b42fe39ce801970d6e4a3f8f\", \"midi_unittests\": \"5b29814c9ff55a1a46cc088a2fa7d8ace4ea9c4f\", \"mojo_core_unittests\": \"2696feb7efbfda89392f7eb3fd6d369ac36884ac\", \"mojo_unittests\": \"9229a35eabca2557e424d71a9b42430c14192675\", \"nacl_helper_nonsfi_unittests\": \"75007727683d0520279fbd206b2f2da3a9a9957a\", \"nacl_loader_unittests\": \"da9628be3e7210fcf4273122e9489badcdc3ec39\", \"native_theme_unittests\": \"3567165ca58e8b93256e7c51806e62c9cae706d9\", \"net_unittests\": \"66560f783a0f6db8948bc2cb755c1ac84bfc5a01\", \"ozone_gl_unittests\": \"fc5931919a752554164eda376622c17cf8e0fa97\", \"ozone_unittests\": \"2ffe5b9c88200751dca63030ab0ed2192a11af7a\", \"ozone_x11_unittests\": \"42a48d53b718cef014f9cf95837e9ecf5dd561b7\", \"pdf_unittests\": \"e5ee6a7c7a8047ad296e78c7fd4158588abc163b\", \"perfetto_unittests\": \"d035089cba3abfcc539df6f95cee082f2f965ee3\", \"ppapi_unittests\": \"76d0c379b224af44ce4578e4989e6b5f118e934c\", \"printing_unittests\": \"bc46651bc1932ffe0e704fe0d471442a0e98533c\", \"remoting_unittests\": \"ee6393dfa2e0d347aed68b5594de1b18e732dc66\", \"sandbox_linux_unittests\": \"54057d99451d0b802ef1a948d85c6547eaede650\", \"service_manager_unittests\": \"2e7875d542ac0e87fe008b15bcf9e3f73b72b146\", \"services_unittests\": \"d3cf96989ac697bdaaedacf07bf90b163a29eb24\", \"shell_dialogs_unittests\": \"5e51049fc4e5aef7d82ac0807b387801b29f40d7\", \"skia_unittests\": \"a887e8bd871e28341c8e471bf640e0d26eb5cc13\", \"snapshot_unittests\": \"30f4123e07718bcecaef3417ebd4c68eb20e0573\", \"sql_unittests\": \"fb8ea84e746635d4c3b4575750e3fca856ef2654\", \"standalone_angle_unittests\": \"f7c6b99ccdd4964f831511286c63f4586dac1645\", \"storage_unittests\": \"4be1510bcb06f4c5e13684adb5a794bc4d649dad\", \"sync_integration_tests\": \"0d1168f106731e5972ddd82fba1281e39312d33b\", \"traffic_annotation_auditor_unittests\": \"4667ef21545a0b1982c6fc987d8680b79aaf1dcf\", \"ui_base_unittests\": \"04e4f796e485d5c1b73931e92bb8b71b882ff241\", \"ui_chromeos_unittests\": \"4735c68cab91d34e8e80ee281cdec07953fec901\", \"ui_touch_selection_unittests\": \"f7313050b2b1452ef7c3885362e42db157011915\", \"unit_tests\": \"59acaaec897156d49cdba51b852be47d09079a91\", \"url_unittests\": \"aa710b1b19e05e7c57548a921eebd455c33c3a16\", \"usage_time_limit_unittests\": \"82ecdd006a31b24e49b7233208eed9da5396eb1c\", \"views_unittests\": \"8173b49470ab8d3fc90990d0a856142ff5ffe136\", \"viz_unittests\": \"08ba5398b607a4c4eb4b45a7ebc2ff2fc74c0253\", \"wm_unittests\": \"780f0e4f945f1053828d13a5e35997ac3aef13c0\", \"wtf_unittests\": \"cd292471820b253df5c2c69a04ed74b57110f9c3\", \"zlib_unittests\": \"ca2399df40ebd579be5a2fe46b8cb09ac7e254d8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_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-9-csso\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603405914453094",
"status": "COMPLETED",
"status_changed_ts": "1603411724618664",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20969",
"builder:linux-chromeos-dbg",
"buildset:commit/git/259e147f157ca259d9d29abcda624aeac3981343",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/259e147f157ca259d9d29abcda624aeac3981343",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031131032757786080",
"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/8865700970442272704/+/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:4f6b3f0cf66a3e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603411724623352",
"url": "https://ci.chromium.org/b/8865700970442272704",
"utcnow_ts": "1603411725504429"
}
}
[00:08:45.521] Invocation finished in 1h40m17.353803136s with status SUCCEEDED