[20:27:56.415] New invocation is queued and will start shortly
[20:27:57.576] Starting the invocation (attempt 1)
[20:27:57.624] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:27:57.624] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:27:57.624] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083866050265433312",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0129383eb2f98acc501471c985cedca9f42d861b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083866050265433312",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/0129383eb2f98acc501471c985cedca9f42d861b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0129383eb2f98acc501471c985cedca9f42d861b",
"gitiles_ref:refs/heads/master"
]
}
[20:27:59.469] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553113677815527",
"id": "8918435988129311056",
"lease_expiration_ts": "1553232478770648",
"lease_key": "358751823",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0129383eb2f98acc501471c985cedca9f42d861b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553113679279529",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11472",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0129383eb2f98acc501471c985cedca9f42d861b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0129383eb2f98acc501471c985cedca9f42d861b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083866050265433312",
"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/8918435988129311056/+/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:43b59b8a6fb4d410",
"user_agent:luci-scheduler"
],
"updated_ts": "1553113679279635",
"url": "https://ci.chromium.org/b/8918435988129311056",
"utcnow_ts": "1553113679456093"
}
}
[20:27:59.469] Task URL: https://ci.chromium.org/b/8918435988129311056
[20:27:59.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:2:0) after 1m0s
[20:28:40.529] Received PubSub notification, asking Buildbucket for the build status
[20:28:40.863] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:59.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:2:0)
[20:28:59.490] Timer tick, asking Buildbucket for the build status
[20:29:00.068] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:00.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:4:0) after 1m0s
[20:30:00.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:4:0)
[20:30:00.095] Timer tick, asking Buildbucket for the build status
[20:30:01.020] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:01.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:5:0) after 1m0s
[20:31:01.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:5:0)
[20:31:01.038] Timer tick, asking Buildbucket for the build status
[20:31:02.292] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:02.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:6:0) after 1m0s
[20:32:02.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:6:0)
[20:32:02.318] Timer tick, asking Buildbucket for the build status
[20:32:02.420] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:02.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:7:0) after 1m0s
[20:33:02.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:7:0)
[20:33:02.502] Timer tick, asking Buildbucket for the build status
[20:33:02.667] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:02.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:8:0) after 1m0s
[20:34:02.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:8:0)
[20:34:02.662] Timer tick, asking Buildbucket for the build status
[20:34:02.792] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:02.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:9:0) after 1m0s
[20:35:02.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:9:0)
[20:35:02.779] Timer tick, asking Buildbucket for the build status
[20:35:03.079] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:03.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:10:0) after 1m0s
[20:36:03.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:10:0)
[20:36:03.047] Timer tick, asking Buildbucket for the build status
[20:36:03.402] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:03.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:11:0) after 1m0s
[20:37:03.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:11:0)
[20:37:03.402] Timer tick, asking Buildbucket for the build status
[20:37:04.303] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:04.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:12:0) after 1m0s
[20:38:04.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:12:0)
[20:38:04.324] Timer tick, asking Buildbucket for the build status
[20:38:05.202] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:05.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:13:0) after 1m0s
[20:39:05.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:13:0)
[20:39:05.189] Timer tick, asking Buildbucket for the build status
[20:39:05.586] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:05.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:14:0) after 1m0s
[20:40:05.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:14:0)
[20:40:05.604] Timer tick, asking Buildbucket for the build status
[20:40:05.728] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:05.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:15:0) after 1m0s
[20:41:05.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:15:0)
[20:41:05.758] Timer tick, asking Buildbucket for the build status
[20:41:05.898] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:05.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:16:0) after 1m0s
[20:42:05.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:16:0)
[20:42:05.919] Timer tick, asking Buildbucket for the build status
[20:42:06.812] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:06.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:17:0) after 1m0s
[20:43:06.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:17:0)
[20:43:06.857] Timer tick, asking Buildbucket for the build status
[20:43:07.099] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:07.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:18:0) after 1m0s
[20:44:07.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:18:0)
[20:44:07.121] Timer tick, asking Buildbucket for the build status
[20:44:07.228] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:07.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:19:0) after 1m0s
[20:45:07.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:19:0)
[20:45:07.229] Timer tick, asking Buildbucket for the build status
[20:45:07.866] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:07.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:20:0) after 1m0s
[20:46:07.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:20:0)
[20:46:07.909] Timer tick, asking Buildbucket for the build status
[20:46:08.006] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:08.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:21:0) after 1m0s
[20:47:07.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:21:0)
[20:47:07.995] Timer tick, asking Buildbucket for the build status
[20:47:08.471] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:08.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:22:0) after 1m0s
[20:48:08.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:22:0)
[20:48:08.448] Timer tick, asking Buildbucket for the build status
[20:48:08.589] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:08.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:23:0) after 1m0s
[20:49:08.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:23:0)
[20:49:08.599] Timer tick, asking Buildbucket for the build status
[20:49:08.730] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:08.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:24:0) after 1m0s
[20:50:08.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:24:0)
[20:50:08.756] Timer tick, asking Buildbucket for the build status
[20:50:09.578] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:09.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:25:0) after 1m0s
[20:51:09.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:25:0)
[20:51:09.585] Timer tick, asking Buildbucket for the build status
[20:51:10.026] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:10.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:26:0) after 1m0s
[20:52:10.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:26:0)
[20:52:10.069] Timer tick, asking Buildbucket for the build status
[20:52:10.168] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:10.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:27:0) after 1m0s
[20:53:10.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:27:0)
[20:53:10.154] Timer tick, asking Buildbucket for the build status
[20:53:10.785] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:10.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:28:0) after 1m0s
[20:54:10.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:28:0)
[20:54:10.806] Timer tick, asking Buildbucket for the build status
[20:54:11.077] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:11.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:29:0) after 1m0s
[20:55:11.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:29:0)
[20:55:11.081] Timer tick, asking Buildbucket for the build status
[20:55:11.354] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:11.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:30:0) after 1m0s
[20:56:11.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:30:0)
[20:56:11.456] Timer tick, asking Buildbucket for the build status
[20:56:12.011] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:12.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:31:0) after 1m0s
[20:57:12.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:31:0)
[20:57:12.031] Timer tick, asking Buildbucket for the build status
[20:57:12.142] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:12.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:32:0) after 1m0s
[20:58:12.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:32:0)
[20:58:12.163] Timer tick, asking Buildbucket for the build status
[20:58:12.283] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:12.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:33:0) after 1m0s
[20:59:12.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:33:0)
[20:59:12.270] Timer tick, asking Buildbucket for the build status
[20:59:12.481] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:12.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:34:0) after 1m0s
[21:00:12.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:34:0)
[21:00:12.513] Timer tick, asking Buildbucket for the build status
[21:00:12.989] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:12.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:35:0) after 1m0s
[21:01:12.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:35:0)
[21:01:12.988] Timer tick, asking Buildbucket for the build status
[21:01:13.222] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:13.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:36:0) after 1m0s
[21:02:13.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:36:0)
[21:02:13.293] Timer tick, asking Buildbucket for the build status
[21:02:13.607] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:13.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:37:0) after 1m0s
[21:03:13.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:37:0)
[21:03:13.636] Timer tick, asking Buildbucket for the build status
[21:03:13.790] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:13.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:38:0) after 1m0s
[21:04:13.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:38:0)
[21:04:13.844] Timer tick, asking Buildbucket for the build status
[21:04:14.025] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:14.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:39:0) after 1m0s
[21:05:15.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:39:0)
[21:05:15.088] Timer tick, asking Buildbucket for the build status
[21:05:18.846] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:18.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:40:0) after 1m0s
[21:06:18.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:40:0)
[21:06:18.986] Timer tick, asking Buildbucket for the build status
[21:06:19.553] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:19.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:41:0) after 1m0s
[21:07:19.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:41:0)
[21:07:19.627] Timer tick, asking Buildbucket for the build status
[21:07:19.786] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:19.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:42:0) after 1m0s
[21:08:19.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:42:0)
[21:08:19.814] Timer tick, asking Buildbucket for the build status
[21:08:19.964] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:19.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:43:0) after 1m0s
[21:09:19.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:43:0)
[21:09:19.983] Timer tick, asking Buildbucket for the build status
[21:09:20.183] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:20.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:44:0) after 1m0s
[21:10:20.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:44:0)
[21:10:20.217] Timer tick, asking Buildbucket for the build status
[21:10:20.368] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:20.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:45:0) after 1m0s
[21:11:20.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:45:0)
[21:11:20.392] Timer tick, asking Buildbucket for the build status
[21:11:20.717] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:20.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:46:0) after 1m0s
[21:12:20.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:46:0)
[21:12:20.764] Timer tick, asking Buildbucket for the build status
[21:12:21.189] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:21.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:47:0) after 1m0s
[21:13:21.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:47:0)
[21:13:21.256] Timer tick, asking Buildbucket for the build status
[21:13:21.361] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:21.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:48:0) after 1m0s
[21:14:21.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:48:0)
[21:14:21.482] Timer tick, asking Buildbucket for the build status
[21:14:21.894] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:21.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:49:0) after 1m0s
[21:15:21.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:49:0)
[21:15:21.959] Timer tick, asking Buildbucket for the build status
[21:15:22.099] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:50:0) after 1m0s
[21:16:22.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:50:0)
[21:16:22.076] Timer tick, asking Buildbucket for the build status
[21:16:22.199] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:22.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:51:0) after 1m0s
[21:17:22.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:51:0)
[21:17:22.196] Timer tick, asking Buildbucket for the build status
[21:17:23.194] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:23.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:52:0) after 1m0s
[21:18:23.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:52:0)
[21:18:23.350] Timer tick, asking Buildbucket for the build status
[21:18:24.402] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:24.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:53:0) after 1m0s
[21:19:24.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:53:0)
[21:19:24.379] Timer tick, asking Buildbucket for the build status
[21:19:24.978] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:24.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:54:0) after 1m0s
[21:20:24.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:54:0)
[21:20:24.964] Timer tick, asking Buildbucket for the build status
[21:20:25.070] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:25.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:55:0) after 1m0s
[21:21:25.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:55:0)
[21:21:25.072] Timer tick, asking Buildbucket for the build status
[21:21:25.700] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:25.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:56:0) after 1m0s
[21:22:25.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:56:0)
[21:22:25.721] Timer tick, asking Buildbucket for the build status
[21:22:25.865] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:57:0) after 1m0s
[21:23:25.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:57:0)
[21:23:25.906] Timer tick, asking Buildbucket for the build status
[21:23:26.379] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:26.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:58:0) after 1m0s
[21:24:26.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:58:0)
[21:24:26.388] Timer tick, asking Buildbucket for the build status
[21:24:26.558] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:26.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:59:0) after 1m0s
[21:25:26.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:59:0)
[21:25:26.586] Timer tick, asking Buildbucket for the build status
[21:25:26.868] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:26.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:60:0) after 1m0s
[21:26:26.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:60:0)
[21:26:26.847] Timer tick, asking Buildbucket for the build status
[21:26:26.974] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:26.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:61:0) after 1m0s
[21:27:26.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:61:0)
[21:27:26.979] Timer tick, asking Buildbucket for the build status
[21:27:27.166] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:27.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:62:0) after 1m0s
[21:28:27.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:62:0)
[21:28:27.163] Timer tick, asking Buildbucket for the build status
[21:28:27.476] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:27.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:63:0) after 1m0s
[21:29:27.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:63:0)
[21:29:27.495] Timer tick, asking Buildbucket for the build status
[21:29:27.706] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:27.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:64:0) after 1m0s
[21:30:27.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:64:0)
[21:30:27.693] Timer tick, asking Buildbucket for the build status
[21:30:28.002] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:28.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:65:0) after 1m0s
[21:31:28.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:65:0)
[21:31:28.036] Timer tick, asking Buildbucket for the build status
[21:31:28.173] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:28.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:66:0) after 1m0s
[21:32:28.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:66:0)
[21:32:28.194] Timer tick, asking Buildbucket for the build status
[21:32:28.306] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:67:0) after 1m0s
[21:33:28.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:67:0)
[21:33:28.329] Timer tick, asking Buildbucket for the build status
[21:33:28.555] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:68:0) after 1m0s
[21:34:28.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:68:0)
[21:34:28.572] Timer tick, asking Buildbucket for the build status
[21:34:28.869] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:69:0) after 1m0s
[21:35:28.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:69:0)
[21:35:28.888] Timer tick, asking Buildbucket for the build status
[21:35:29.392] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:29.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:70:0) after 1m0s
[21:36:29.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:70:0)
[21:36:29.413] Timer tick, asking Buildbucket for the build status
[21:36:29.809] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:29.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:71:0) after 1m0s
[21:37:29.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:71:0)
[21:37:29.819] Timer tick, asking Buildbucket for the build status
[21:37:30.013] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:72:0) after 1m0s
[21:38:30.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:72:0)
[21:38:30.000] Timer tick, asking Buildbucket for the build status
[21:38:30.153] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:30.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:73:0) after 1m0s
[21:39:30.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:73:0)
[21:39:30.131] Timer tick, asking Buildbucket for the build status
[21:39:30.348] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:30.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:74:0) after 1m0s
[21:40:30.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:74:0)
[21:40:30.326] Timer tick, asking Buildbucket for the build status
[21:40:30.719] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:30.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:75:0) after 1m0s
[21:41:30.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:75:0)
[21:41:30.732] Timer tick, asking Buildbucket for the build status
[21:41:30.898] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:30.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:76:0) after 1m0s
[21:42:30.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:76:0)
[21:42:30.922] Timer tick, asking Buildbucket for the build status
[21:42:31.087] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:31.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:77:0) after 1m0s
[21:43:31.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:77:0)
[21:43:31.108] Timer tick, asking Buildbucket for the build status
[21:43:31.516] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:31.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:78:0) after 1m0s
[21:44:31.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:78:0)
[21:44:31.536] Timer tick, asking Buildbucket for the build status
[21:44:31.810] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:31.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:79:0) after 1m0s
[21:45:31.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:79:0)
[21:45:31.815] Timer tick, asking Buildbucket for the build status
[21:45:32.000] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:32.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:80:0) after 1m0s
[21:46:32.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:80:0)
[21:46:32.024] Timer tick, asking Buildbucket for the build status
[21:46:32.400] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:32.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:81:0) after 1m0s
[21:47:32.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:81:0)
[21:47:32.376] Timer tick, asking Buildbucket for the build status
[21:47:33.456] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:33.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:82:0) after 1m0s
[21:48:33.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:82:0)
[21:48:33.515] Timer tick, asking Buildbucket for the build status
[21:48:33.813] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:33.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:83:0) after 1m0s
[21:49:33.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:83:0)
[21:49:33.799] Timer tick, asking Buildbucket for the build status
[21:49:34.159] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:34.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:84:0) after 1m0s
[21:50:36.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:84:0)
[21:50:36.146] Timer tick, asking Buildbucket for the build status
[21:50:46.953] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:46.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:85:0) after 1m0s
[21:51:46.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:85:0)
[21:51:46.978] Timer tick, asking Buildbucket for the build status
[21:51:47.392] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:47.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:86:0) after 1m0s
[21:52:47.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:86:0)
[21:52:47.415] Timer tick, asking Buildbucket for the build status
[21:52:47.535] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:47.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:87:0) after 1m0s
[21:53:47.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:87:0)
[21:53:47.559] Timer tick, asking Buildbucket for the build status
[21:53:47.668] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:47.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:88:0) after 1m0s
[21:54:47.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:88:0)
[21:54:47.697] Timer tick, asking Buildbucket for the build status
[21:54:48.180] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:48.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:89:0) after 1m0s
[21:55:48.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:89:0)
[21:55:48.173] Timer tick, asking Buildbucket for the build status
[21:55:48.402] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:48.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:90:0) after 1m0s
[21:56:48.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:90:0)
[21:56:48.380] Timer tick, asking Buildbucket for the build status
[21:56:48.478] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:48.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:91:0) after 1m0s
[21:57:48.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:91:0)
[21:57:48.469] Timer tick, asking Buildbucket for the build status
[21:57:48.568] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:48.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:92:0) after 1m0s
[21:58:48.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:92:0)
[21:58:48.598] Timer tick, asking Buildbucket for the build status
[21:58:49.483] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:49.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:93:0) after 1m0s
[21:59:49.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:93:0)
[21:59:49.514] Timer tick, asking Buildbucket for the build status
[21:59:50.157] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:50.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:94:0) after 1m0s
[22:00:50.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:94:0)
[22:00:50.203] Timer tick, asking Buildbucket for the build status
[22:00:50.959] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:50.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:95:0) after 1m0s
[22:01:50.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:95:0)
[22:01:50.948] Timer tick, asking Buildbucket for the build status
[22:01:51.278] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:51.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:96:0) after 1m0s
[22:02:51.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:96:0)
[22:02:51.294] Timer tick, asking Buildbucket for the build status
[22:02:51.666] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:51.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:97:0) after 1m0s
[22:03:51.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:97:0)
[22:03:51.659] Timer tick, asking Buildbucket for the build status
[22:03:51.792] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:51.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:98:0) after 1m0s
[22:04:51.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:98:0)
[22:04:51.784] Timer tick, asking Buildbucket for the build status
[22:04:51.912] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:99:0) after 1m0s
[22:05:51.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:99:0)
[22:05:51.942] Timer tick, asking Buildbucket for the build status
[22:05:52.413] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:52.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:100:0) after 1m0s
[22:06:52.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:100:0)
[22:06:52.437] Timer tick, asking Buildbucket for the build status
[22:06:52.582] Build 8918435988129311056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:52.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083866050265433312:101:0) after 1m0s
[22:07:44.962] Received PubSub notification, asking Buildbucket for the build status
[22:07:45.158] Build 8918435988129311056: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:07:45.158] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553119663330131",
"created_by": "project:chromium",
"created_ts": "1553113677815527",
"failure_reason": "BUILD_FAILURE",
"id": "8918435988129311056",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0129383eb2f98acc501471c985cedca9f42d861b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"linux-chromeos-dbg\"], \"kvm\": [\"1\"], \"id\": [\"swarm1567-c4\"], \"server_version\": [\"4211-90dafba\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure non_single_process_mash_ash_unittests\\nFailure ash_unittests\\nFailure non_network_service_interactive_ui_tests\\nFailure non_network_service_browser_tests\"}, \"properties\": {\"swarm_hashes_refs/heads/master(at){#642639}_without_patch\": {\"mojo_core_unittests\": \"b5f4ce22757cb68e0bd6fffb6dc0d2d27f54fd17\", \"leveldb_service_unittests\": \"884c5cb4ee72b7f09da93bbe81951df8a4535c6c\", \"storage_unittests\": \"85d46c18e2e5001389f117528642c996f4f92e87\", \"compositor_unittests\": \"07a89db0656055abb9b9ca563a51c95799e38567\", \"angle_unittests\": \"b9d9add6da5275e93132ce84c51ba3b60b0e1cfd\", \"cast_unittests\": \"2e2a8ce131d3d141ae3d5828f8e7a7c302006e7a\", \"blink_unittests\": \"beab653505f21be2c8332e6f8d8d3acd8a692947\", \"cc_unittests\": \"9025092a7eccc79931f3798ef9dfde4b8972373b\", \"blink_common_unittests\": \"ee8ff6764b132b2f1c810f08c8975c714266948d\", \"chromeos_components_unittests\": \"114821f186468d0362ee10f808acd633658f9f04\", \"shell_dialogs_unittests\": \"6ed2c72653660cd1676eeff68a6a15e1f9bcd20a\", \"message_center_unittests\": \"c2e31e62edf0642a04e234c739cd8c08b8f76c5c\", \"ppapi_unittests\": \"2edbbfdfb37359da08e638e0bd3d9bcbb10c878e\", \"extensions_unittests\": \"1f80d0561a69a553029ff5fd34716a26f1e93612\", \"gl_unittests_ozone\": \"5cbc026294b9c537cdcc6f7dd94031798bfb0667\", \"app_shell_unittests\": \"9094e37b4cfad70883a536d887d4b4e61991de2a\", \"chrome_app_unittests\": \"e1991ac6e93b1a0d49ee890ade13b1d4bde35aef\", \"gcm_unit_tests\": \"b6fc1905370bd40278c9d892503b8c1acb5003d1\", \"views_unittests\": \"a457757d736ad66008c103eb6559f955bf56fc6b\", \"ash_unittests\": \"4eedffac173642c143c967cec83523ab055844e0\", \"ui_base_unittests\": \"1ce5e03fc3b5028be557ecf1d987278089c5851c\", \"wm_unittests\": \"4af8937c93b481e9ad2863978812908422d80895\", \"boringssl_ssl_tests\": \"4df45f006b0771b6737041713df7c476d3e0e909\", \"gin_unittests\": \"5a619232d7d341981f5c2aa5572f70ba02fcacb0\", \"extensions_browsertests\": \"6852f727256350feeefaae22f32013fdf1364949\", \"content_unittests\": \"8859904c90c67b302ea98c146599114f15a5b2b0\", \"ozone_gl_unittests\": \"ebd1840eae1f6d0968c0e1fab73f35ddb64f085b\", \"traffic_annotation_auditor_unittests\": \"bc109d81ab7c8b0209a33775fda3da66ff96f369\", \"libjingle_xmpp_unittests\": \"2d5ce06efdc106ca96c01fc8c5b4468bf7c0f611\", \"remoting_unittests\": \"132f4172853c7483d88960eb99e0077e784aa24d\", \"filesystem_service_unittests\": \"b034507097f43b9d53590b9ded351620161ba852\", \"gfx_unittests\": \"d08f572742e8547bd7c252af61f484baf8aa463a\", \"latency_unittests\": \"484d513c2de1122bc67e71d2d8288b4a1f441a09\", \"usage_time_limit_unittests\": \"8ec9dcfee4928eae04112540187e4bd0d1da8a05\", \"boringssl_crypto_tests\": \"4293705b4a8d95cba86a1bb881a3c55c3357ed7f\", \"display_unittests\": \"990a47a01e560b7f3072f37f9ba921e15a79b1ae\", \"chromedriver_unittests\": \"e54c2a1a5d40f53af7c8ed502c080f1b68e537b6\", \"sandbox_linux_unittests\": \"5c692a7c0f85b492f6f88862655a0174c2320140\", \"chromeos_unittests\": \"70f15beca4c4d1fb3a59b51b403f202faf70e1ab\", \"media_service_unittests\": \"3baa772c5582159df7733a1a2484f516d15bd4bc\", \"jingle_unittests\": \"5a12892f016eda4a3b7f0e8fe52f644d5ab44847\", \"capture_unittests\": \"195c4556619ad1b83646b0dceba6af227cf81d09\", \"ui_chromeos_unittests\": \"00496dca28c20c156921ced82c9ffb0313c582c9\", \"service_manager_unittests\": \"bdeafad1d77d53b6647eb002cf24341f6d787fcb\", \"media_blink_unittests\": \"7d7135ee0702995d84b83d05ce89c504aaf4ea7a\", \"components_unittests\": \"4a11576f6f5f7ad5270d3b620842f853dc04eff4\", \"snapshot_unittests\": \"fbf1cf9fe081f2996c24190673d80a1189636148\", \"accessibility_unittests\": \"4793d9fa6a572e221e2eb262b4f75f6f944d359b\", \"aura_unittests\": \"c759550bb4455f64c7577747574afbc25c2efaef\", \"services_unittests\": \"e9be4f8d826d0a8df3960d65a268740ee40cad26\", \"ozone_unittests\": \"50008b5c68782ebd9f50142fd9ccab7efab75eec\", \"events_unittests\": \"a30661f1e73e2d50b8bdefd14f235334dab268f7\", \"ui_touch_selection_unittests\": \"fa186213ca135dab0cd66b3806544736ffb78e4c\", \"cacheinvalidation_unittests\": \"5ccf47ad062e5c84e240249ae7c7db37bf579d4e\", \"viz_unittests\": \"cffe63a7dc9268a9f9be0c411f7f513bebb42094\", \"browser_tests\": \"0ab9b1f7243f394b95ed3975ceb9f28960cbee4b\", \"net_unittests\": \"15fa02dee2626be7a8e2bb115de2c547837e18ff\", \"midi_unittests\": \"0d441f07204b4507bdbe13894e9bc457208e07f5\", \"dbus_unittests\": \"24869d3b95f2db2c13b0b398152e76f8bb320e2f\", \"gpu_unittests\": \"399c4bfa72dcdf6b1fdc9f0d7f805ef62d051221\", \"exo_unittests\": \"84a55865e770b47de8e1afbfd6af2b305e481281\", \"native_theme_unittests\": \"5cb712538d91db4e1382230728508c3e5124c8d8\", \"base_unittests\": \"82ca9b44bb5104443cefcb5c847edc2dab9f24c9\", \"interactive_ui_tests\": \"68906278932c931d65de93ad3647115e5074f335\", \"mojo_unittests\": \"a03d6cf51aa69868cf5b0fd083b5ffdf2f59e336\", \"url_unittests\": \"c73b46931b72ef43ef7ef6fd615da0e6da5f19c6\", \"google_apis_unittests\": \"563b025a17585446c54477d70b3affd4b5316634\", \"blink_heap_unittests\": \"688826c3da540483373a5e03968a0b9b58b06369\", \"ozone_x11_unittests\": \"e219bfad54c550b6b2be1d07a0a22366d20c5045\", \"sync_integration_tests\": \"3869cf4bc8254aa8af54f43dfb88bd35600c015d\", \"sql_unittests\": \"0408b950104e104098008217082b960f30cdee77\", \"nacl_helper_nonsfi_unittests\": \"08999855de6895a727c9b35afc8d1f926d3c9274\", \"keyboard_unittests\": \"19c1a9a7954ae90e192efbf8efd03219b33c4f37\", \"blink_fuzzer_unittests\": \"f4c1cc7daf1b39196b4150fecccad365bf91f404\", \"app_list_unittests\": \"c2d851f6bcf34267fef089cc0dc2cd1989179140\", \"ipc_tests\": \"cf7dcf12e65d62834bba0572814a06b1296eb628\", \"perfetto_unittests\": \"fe0331b24fcc8922c7d740886516394b6260109a\", \"blink_platform_unittests\": \"26d76b98f98553b20c8da979d4926992511857df\", \"components_browsertests\": \"8c21a0a68debdbea89d7f3b75c1ec14ef655f00e\", \"media_unittests\": \"bc4be1af8335e6c352d3b843e1824d9e6947828f\", \"wtf_unittests\": \"e6720497e9a6d46e5afcf4bd7c4c7e867a135268\", \"pdf_unittests\": \"effbc2460283e5d32e217775f413fcbeee3c8c98\", \"skia_unittests\": \"90a4a189edb26373bcf39774f065ab7a94f12414\", \"crypto_unittests\": \"177da94ca42ed8139fba0d2af1911f9c1f9cdf58\", \"nacl_loader_unittests\": \"8d81059f69849dfc3f9df8d9fe68488426e78a1a\", \"unit_tests\": \"644bc9344fc4ea605df23bf12b137f222d7f3013\", \"content_browsertests\": \"44ce7406533a316f0b5530174a64504c784291df\", \"printing_unittests\": \"b7b908f9ab817256283996c67ad56d8a2994f6da\", \"device_unittests\": \"c6d20a32d7b67bb98f7b3bf653f7cf7e48a6fc6e\"}, \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_revision\": \"0129383eb2f98acc501471c985cedca9f42d861b\", \"recipe\": \"chromium\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_dawn_revision\": \"54e4d47db4910ebd1ffce0247b60d4e6f984774f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27203}\", \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#642639}\", \"branch\": \"refs/heads/master\", \"swarm_hashes\": {\"mojo_core_unittests\": \"b5f4ce22757cb68e0bd6fffb6dc0d2d27f54fd17\", \"leveldb_service_unittests\": \"884c5cb4ee72b7f09da93bbe81951df8a4535c6c\", \"storage_unittests\": \"85d46c18e2e5001389f117528642c996f4f92e87\", \"jingle_unittests\": \"5a12892f016eda4a3b7f0e8fe52f644d5ab44847\", \"angle_unittests\": \"b9d9add6da5275e93132ce84c51ba3b60b0e1cfd\", \"cast_unittests\": \"2e2a8ce131d3d141ae3d5828f8e7a7c302006e7a\", \"blink_unittests\": \"beab653505f21be2c8332e6f8d8d3acd8a692947\", \"cc_unittests\": \"9025092a7eccc79931f3798ef9dfde4b8972373b\", \"blink_common_unittests\": \"ee8ff6764b132b2f1c810f08c8975c714266948d\", \"chromeos_components_unittests\": \"114821f186468d0362ee10f808acd633658f9f04\", \"shell_dialogs_unittests\": \"6ed2c72653660cd1676eeff68a6a15e1f9bcd20a\", \"message_center_unittests\": \"c2e31e62edf0642a04e234c739cd8c08b8f76c5c\", \"ppapi_unittests\": \"2edbbfdfb37359da08e638e0bd3d9bcbb10c878e\", \"extensions_unittests\": \"1f80d0561a69a553029ff5fd34716a26f1e93612\", \"gl_unittests_ozone\": \"5cbc026294b9c537cdcc6f7dd94031798bfb0667\", \"app_shell_unittests\": \"9094e37b4cfad70883a536d887d4b4e61991de2a\", \"chrome_app_unittests\": \"e1991ac6e93b1a0d49ee890ade13b1d4bde35aef\", \"gcm_unit_tests\": \"b6fc1905370bd40278c9d892503b8c1acb5003d1\", \"views_unittests\": \"a457757d736ad66008c103eb6559f955bf56fc6b\", \"ash_unittests\": \"4eedffac173642c143c967cec83523ab055844e0\", \"ui_base_unittests\": \"1ce5e03fc3b5028be557ecf1d987278089c5851c\", \"wm_unittests\": \"4af8937c93b481e9ad2863978812908422d80895\", \"boringssl_ssl_tests\": \"4df45f006b0771b6737041713df7c476d3e0e909\", \"gpu_unittests\": \"399c4bfa72dcdf6b1fdc9f0d7f805ef62d051221\", \"extensions_browsertests\": \"6852f727256350feeefaae22f32013fdf1364949\", \"content_unittests\": \"8859904c90c67b302ea98c146599114f15a5b2b0\", \"ozone_gl_unittests\": \"ebd1840eae1f6d0968c0e1fab73f35ddb64f085b\", \"traffic_annotation_auditor_unittests\": \"bc109d81ab7c8b0209a33775fda3da66ff96f369\", \"libjingle_xmpp_unittests\": \"2d5ce06efdc106ca96c01fc8c5b4468bf7c0f611\", \"remoting_unittests\": \"132f4172853c7483d88960eb99e0077e784aa24d\", \"filesystem_service_unittests\": \"b034507097f43b9d53590b9ded351620161ba852\", \"gfx_unittests\": \"d08f572742e8547bd7c252af61f484baf8aa463a\", \"latency_unittests\": \"484d513c2de1122bc67e71d2d8288b4a1f441a09\", \"usage_time_limit_unittests\": \"8ec9dcfee4928eae04112540187e4bd0d1da8a05\", \"boringssl_crypto_tests\": \"4293705b4a8d95cba86a1bb881a3c55c3357ed7f\", \"display_unittests\": \"990a47a01e560b7f3072f37f9ba921e15a79b1ae\", \"chromedriver_unittests\": \"e54c2a1a5d40f53af7c8ed502c080f1b68e537b6\", \"sandbox_linux_unittests\": \"5c692a7c0f85b492f6f88862655a0174c2320140\", \"chromeos_unittests\": \"70f15beca4c4d1fb3a59b51b403f202faf70e1ab\", \"media_service_unittests\": \"3baa772c5582159df7733a1a2484f516d15bd4bc\", \"compositor_unittests\": \"07a89db0656055abb9b9ca563a51c95799e38567\", \"capture_unittests\": \"195c4556619ad1b83646b0dceba6af227cf81d09\", \"ui_chromeos_unittests\": \"00496dca28c20c156921ced82c9ffb0313c582c9\", \"service_manager_unittests\": \"bdeafad1d77d53b6647eb002cf24341f6d787fcb\", \"media_blink_unittests\": \"7d7135ee0702995d84b83d05ce89c504aaf4ea7a\", \"components_unittests\": \"4a11576f6f5f7ad5270d3b620842f853dc04eff4\", \"snapshot_unittests\": \"fbf1cf9fe081f2996c24190673d80a1189636148\", \"accessibility_unittests\": \"4793d9fa6a572e221e2eb262b4f75f6f944d359b\", \"aura_unittests\": \"c759550bb4455f64c7577747574afbc25c2efaef\", \"services_unittests\": \"e9be4f8d826d0a8df3960d65a268740ee40cad26\", \"ozone_unittests\": \"50008b5c68782ebd9f50142fd9ccab7efab75eec\", \"events_unittests\": \"a30661f1e73e2d50b8bdefd14f235334dab268f7\", \"ui_touch_selection_unittests\": \"fa186213ca135dab0cd66b3806544736ffb78e4c\", \"cacheinvalidation_unittests\": \"5ccf47ad062e5c84e240249ae7c7db37bf579d4e\", \"viz_unittests\": \"cffe63a7dc9268a9f9be0c411f7f513bebb42094\", \"browser_tests\": \"0ab9b1f7243f394b95ed3975ceb9f28960cbee4b\", \"blink_platform_unittests\": \"26d76b98f98553b20c8da979d4926992511857df\", \"midi_unittests\": \"0d441f07204b4507bdbe13894e9bc457208e07f5\", \"dbus_unittests\": \"24869d3b95f2db2c13b0b398152e76f8bb320e2f\", \"gin_unittests\": \"5a619232d7d341981f5c2aa5572f70ba02fcacb0\", \"exo_unittests\": \"84a55865e770b47de8e1afbfd6af2b305e481281\", \"native_theme_unittests\": \"5cb712538d91db4e1382230728508c3e5124c8d8\", \"base_unittests\": \"82ca9b44bb5104443cefcb5c847edc2dab9f24c9\", \"interactive_ui_tests\": \"68906278932c931d65de93ad3647115e5074f335\", \"mojo_unittests\": \"a03d6cf51aa69868cf5b0fd083b5ffdf2f59e336\", \"url_unittests\": \"c73b46931b72ef43ef7ef6fd615da0e6da5f19c6\", \"google_apis_unittests\": \"563b025a17585446c54477d70b3affd4b5316634\", \"blink_heap_unittests\": \"688826c3da540483373a5e03968a0b9b58b06369\", \"ozone_x11_unittests\": \"e219bfad54c550b6b2be1d07a0a22366d20c5045\", \"sync_integration_tests\": \"3869cf4bc8254aa8af54f43dfb88bd35600c015d\", \"sql_unittests\": \"0408b950104e104098008217082b960f30cdee77\", \"nacl_helper_nonsfi_unittests\": \"08999855de6895a727c9b35afc8d1f926d3c9274\", \"keyboard_unittests\": \"19c1a9a7954ae90e192efbf8efd03219b33c4f37\", \"blink_fuzzer_unittests\": \"f4c1cc7daf1b39196b4150fecccad365bf91f404\", \"app_list_unittests\": \"c2d851f6bcf34267fef089cc0dc2cd1989179140\", \"ipc_tests\": \"cf7dcf12e65d62834bba0572814a06b1296eb628\", \"perfetto_unittests\": \"fe0331b24fcc8922c7d740886516394b6260109a\", \"net_unittests\": \"15fa02dee2626be7a8e2bb115de2c547837e18ff\", \"components_browsertests\": \"8c21a0a68debdbea89d7f3b75c1ec14ef655f00e\", \"media_unittests\": \"bc4be1af8335e6c352d3b843e1824d9e6947828f\", \"wtf_unittests\": \"e6720497e9a6d46e5afcf4bd7c4c7e867a135268\", \"pdf_unittests\": \"effbc2460283e5d32e217775f413fcbeee3c8c98\", \"skia_unittests\": \"90a4a189edb26373bcf39774f065ab7a94f12414\", \"crypto_unittests\": \"177da94ca42ed8139fba0d2af1911f9c1f9cdf58\", \"nacl_loader_unittests\": \"8d81059f69849dfc3f9df8d9fe68488426e78a1a\", \"unit_tests\": \"644bc9344fc4ea605df23bf12b137f222d7f3013\", \"content_browsertests\": \"44ce7406533a316f0b5530174a64504c784291df\", \"printing_unittests\": \"b7b908f9ab817256283996c67ad56d8a2994f6da\", \"device_unittests\": \"c6d20a32d7b67bb98f7b3bf653f7cf7e48a6fc6e\"}, \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"linux-chromeos-dbg\", \"got_webrtc_revision\": \"0b44314b76cb84c855103458e93e2eeb0c6ee2a2\", \"mastername\": \"chromium.chromiumos\", \"got_angle_revision\": \"12a1fe4a5b7a3d4229b971ae9a5ede4c3c08237c\", \"got_v8_revision\": \"da7a3d8e0374cb531d9ce0a3215650eccdd9a8f0\", \"got_v8_revision_cp\": \"refs/heads/7.5.75@{#2}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11472, \"path_config\": \"generic\", \"revision\": \"0129383eb2f98acc501471c985cedca9f42d861b\", \"bot_id\": \"swarm1567-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553113719734943",
"status": "COMPLETED",
"status_changed_ts": "1553119664485066",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11472",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0129383eb2f98acc501471c985cedca9f42d861b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0129383eb2f98acc501471c985cedca9f42d861b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083866050265433312",
"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/8918435988129311056/+/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:43b59b8a6fb4d410",
"user_agent:luci-scheduler"
],
"updated_ts": "1553119664498817",
"url": "https://ci.chromium.org/b/8918435988129311056",
"utcnow_ts": "1553119665144718"
}
}
[22:07:45.158] Invocation finished in 1h39m48.762503996s with status FAILED