[23:22:39.043] New invocation is queued and will start shortly
[23:22:40.744] Starting the invocation (attempt 1)
[23:22:40.784] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:22:40.784] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:22:40.785] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082677297872278336",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"311ef5ce0538468dd5da72ade0b5667ebd822187\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9082677297872278336",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/311ef5ce0538468dd5da72ade0b5667ebd822187",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/311ef5ce0538468dd5da72ade0b5667ebd822187",
"gitiles_ref:refs/heads/master"
]
}
[23:22:41.978] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554247361381328",
"id": "8917247234750781296",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"311ef5ce0538468dd5da72ade0b5667ebd822187\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554247361849026",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11663",
"builder:linux-chromeos-dbg",
"buildset:commit/git/311ef5ce0538468dd5da72ade0b5667ebd822187",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/311ef5ce0538468dd5da72ade0b5667ebd822187",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082677297872278336",
"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/8917247234750781296/+/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": "1554247361849136",
"url": "https://ci.chromium.org/b/8917247234750781296",
"utcnow_ts": "1554247361968199"
}
}
[23:22:41.978] Task URL: https://ci.chromium.org/b/8917247234750781296
[23:22:41.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:2:0) after 1m0s
[23:23:41.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:2:0)
[23:23:41.998] Timer tick, asking Buildbucket for the build status
[23:23:42.287] Build 8917247234750781296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:42.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:3:0) after 1m0s
[23:24:42.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:3:0)
[23:24:42.303] Timer tick, asking Buildbucket for the build status
[23:24:42.676] Build 8917247234750781296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:42.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:4:0) after 1m0s
[23:25:42.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:4:0)
[23:25:42.694] Timer tick, asking Buildbucket for the build status
[23:25:42.827] Build 8917247234750781296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:42.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:5:0) after 1m0s
[23:26:42.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:5:0)
[23:26:42.848] Timer tick, asking Buildbucket for the build status
[23:26:42.943] Build 8917247234750781296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:42.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:6:0) after 1m0s
[23:27:42.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:6:0)
[23:27:42.977] Timer tick, asking Buildbucket for the build status
[23:27:43.108] Build 8917247234750781296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:43.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:7:0) after 1m0s
[23:27:51.377] Received PubSub notification, asking Buildbucket for the build status
[23:27:51.688] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:43.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:7:0)
[23:28:43.087] Timer tick, asking Buildbucket for the build status
[23:28:43.281] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:43.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:9:0) after 1m0s
[23:29:43.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:9:0)
[23:29:43.294] Timer tick, asking Buildbucket for the build status
[23:29:43.717] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:43.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:10:0) after 1m0s
[23:30:43.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:10:0)
[23:30:43.726] Timer tick, asking Buildbucket for the build status
[23:30:43.969] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:43.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:11:0) after 1m0s
[23:31:43.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:11:0)
[23:31:43.993] Timer tick, asking Buildbucket for the build status
[23:31:44.124] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:12:0) after 1m0s
[23:32:44.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:12:0)
[23:32:44.174] Timer tick, asking Buildbucket for the build status
[23:32:44.383] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:44.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:13:0) after 1m0s
[23:33:44.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:13:0)
[23:33:44.399] Timer tick, asking Buildbucket for the build status
[23:33:44.542] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:44.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:14:0) after 1m0s
[23:34:44.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:14:0)
[23:34:44.564] Timer tick, asking Buildbucket for the build status
[23:34:45.022] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:45.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:15:0) after 1m0s
[23:35:45.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:15:0)
[23:35:45.044] Timer tick, asking Buildbucket for the build status
[23:35:45.381] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:45.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:16:0) after 1m0s
[23:36:45.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:16:0)
[23:36:45.358] Timer tick, asking Buildbucket for the build status
[23:36:46.482] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:46.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:17:0) after 1m0s
[23:37:46.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:17:0)
[23:37:46.484] Timer tick, asking Buildbucket for the build status
[23:37:46.780] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:46.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:18:0) after 1m0s
[23:38:46.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:18:0)
[23:38:46.813] Timer tick, asking Buildbucket for the build status
[23:38:46.895] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:46.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:19:0) after 1m0s
[23:39:46.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:19:0)
[23:39:46.879] Timer tick, asking Buildbucket for the build status
[23:39:47.259] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:47.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:20:0) after 1m0s
[23:40:47.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:20:0)
[23:40:47.237] Timer tick, asking Buildbucket for the build status
[23:40:47.353] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:47.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:21:0) after 1m0s
[23:41:47.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:21:0)
[23:41:47.339] Timer tick, asking Buildbucket for the build status
[23:41:47.715] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:47.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:22:0) after 1m0s
[23:42:47.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:22:0)
[23:42:47.709] Timer tick, asking Buildbucket for the build status
[23:42:48.354] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:48.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:23:0) after 1m0s
[23:43:48.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:23:0)
[23:43:48.372] Timer tick, asking Buildbucket for the build status
[23:43:48.503] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:48.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:24:0) after 1m0s
[23:44:48.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:24:0)
[23:44:48.476] Timer tick, asking Buildbucket for the build status
[23:44:48.562] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:48.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:25:0) after 1m0s
[23:45:48.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:25:0)
[23:45:48.538] Timer tick, asking Buildbucket for the build status
[23:45:48.641] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:48.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:26:0) after 1m0s
[23:46:48.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:26:0)
[23:46:48.630] Timer tick, asking Buildbucket for the build status
[23:46:49.367] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:49.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:27:0) after 1m0s
[23:47:49.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:27:0)
[23:47:49.388] Timer tick, asking Buildbucket for the build status
[23:47:50.022] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:50.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:28:0) after 1m0s
[23:48:50.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:28:0)
[23:48:50.067] Timer tick, asking Buildbucket for the build status
[23:48:50.780] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:29:0) after 1m0s
[23:49:50.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:29:0)
[23:49:50.780] Timer tick, asking Buildbucket for the build status
[23:49:51.002] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:51.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:30:0) after 1m0s
[23:50:50.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:30:0)
[23:50:50.981] Timer tick, asking Buildbucket for the build status
[23:50:51.091] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:51.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:31:0) after 1m0s
[23:51:51.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:31:0)
[23:51:51.078] Timer tick, asking Buildbucket for the build status
[23:51:51.180] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:51.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:32:0) after 1m0s
[23:52:51.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:32:0)
[23:52:51.186] Timer tick, asking Buildbucket for the build status
[23:52:51.286] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:51.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:33:0) after 1m0s
[23:53:51.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:33:0)
[23:53:51.267] Timer tick, asking Buildbucket for the build status
[23:53:51.380] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:51.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:34:0) after 1m0s
[23:54:51.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:34:0)
[23:54:51.373] Timer tick, asking Buildbucket for the build status
[23:54:51.456] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:51.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:35:0) after 1m0s
[23:55:51.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:35:0)
[23:55:51.446] Timer tick, asking Buildbucket for the build status
[23:55:51.617] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:51.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:36:0) after 1m0s
[23:56:51.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:36:0)
[23:56:51.638] Timer tick, asking Buildbucket for the build status
[23:56:51.811] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:51.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:37:0) after 1m0s
[23:57:51.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:37:0)
[23:57:51.835] Timer tick, asking Buildbucket for the build status
[23:57:52.468] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:52.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:38:0) after 1m0s
[23:58:52.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:38:0)
[23:58:52.474] Timer tick, asking Buildbucket for the build status
[23:58:52.851] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:52.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:39:0) after 1m0s
[23:59:52.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:39:0)
[23:59:52.872] Timer tick, asking Buildbucket for the build status
[23:59:53.659] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:53.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:40:0) after 1m0s
[00:00:53.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:40:0)
[00:00:53.684] Timer tick, asking Buildbucket for the build status
[00:00:54.214] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:54.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:41:0) after 1m0s
[00:01:54.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:41:0)
[00:01:54.250] Timer tick, asking Buildbucket for the build status
[00:01:54.830] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:54.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:42:0) after 1m0s
[00:02:54.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:42:0)
[00:02:54.848] Timer tick, asking Buildbucket for the build status
[00:02:55.177] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:55.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:43:0) after 1m0s
[00:03:55.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:43:0)
[00:03:55.201] Timer tick, asking Buildbucket for the build status
[00:03:55.758] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:55.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:44:0) after 1m0s
[00:04:55.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:44:0)
[00:04:55.780] Timer tick, asking Buildbucket for the build status
[00:04:55.918] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:55.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:45:0) after 1m0s
[00:05:55.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:45:0)
[00:05:55.940] Timer tick, asking Buildbucket for the build status
[00:05:56.982] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:56.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:46:0) after 1m0s
[00:06:56.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:46:0)
[00:06:56.989] Timer tick, asking Buildbucket for the build status
[00:06:57.175] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:57.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:47:0) after 1m0s
[00:07:57.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:47:0)
[00:07:57.196] Timer tick, asking Buildbucket for the build status
[00:07:57.340] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:57.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:48:0) after 1m0s
[00:08:57.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:48:0)
[00:08:57.361] Timer tick, asking Buildbucket for the build status
[00:08:57.660] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:57.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:49:0) after 1m0s
[00:09:57.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:49:0)
[00:09:57.640] Timer tick, asking Buildbucket for the build status
[00:09:57.986] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:57.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:50:0) after 1m0s
[00:10:58.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:50:0)
[00:10:58.028] Timer tick, asking Buildbucket for the build status
[00:10:58.376] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:58.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:51:0) after 1m0s
[00:11:58.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:51:0)
[00:11:58.400] Timer tick, asking Buildbucket for the build status
[00:11:59.384] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:59.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:52:0) after 1m0s
[00:12:59.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:52:0)
[00:12:59.399] Timer tick, asking Buildbucket for the build status
[00:12:59.507] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:59.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:53:0) after 1m0s
[00:13:59.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:53:0)
[00:13:59.561] Timer tick, asking Buildbucket for the build status
[00:14:00.001] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:00.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:54:0) after 1m0s
[00:14:59.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:54:0)
[00:14:59.976] Timer tick, asking Buildbucket for the build status
[00:15:00.157] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:00.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:55:0) after 1m0s
[00:16:00.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:55:0)
[00:16:00.137] Timer tick, asking Buildbucket for the build status
[00:16:00.338] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:00.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:56:0) after 1m0s
[00:17:00.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:56:0)
[00:17:00.364] Timer tick, asking Buildbucket for the build status
[00:17:01.275] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:01.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:57:0) after 1m0s
[00:18:01.279] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:57:0)
[00:18:01.279] Timer tick, asking Buildbucket for the build status
[00:18:01.950] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:01.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:58:0) after 1m0s
[00:19:01.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:58:0)
[00:19:01.978] Timer tick, asking Buildbucket for the build status
[00:19:02.184] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:02.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:59:0) after 1m0s
[00:20:02.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:59:0)
[00:20:02.204] Timer tick, asking Buildbucket for the build status
[00:20:03.104] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:03.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:60:0) after 1m0s
[00:21:03.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:60:0)
[00:21:03.126] Timer tick, asking Buildbucket for the build status
[00:21:03.752] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:03.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:61:0) after 1m0s
[00:22:03.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:61:0)
[00:22:03.739] Timer tick, asking Buildbucket for the build status
[00:22:03.873] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:03.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:62:0) after 1m0s
[00:23:03.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:62:0)
[00:23:03.901] Timer tick, asking Buildbucket for the build status
[00:23:04.044] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:04.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:63:0) after 1m0s
[00:24:04.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:63:0)
[00:24:04.073] Timer tick, asking Buildbucket for the build status
[00:24:04.496] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:04.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:64:0) after 1m0s
[00:25:04.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:64:0)
[00:25:04.530] Timer tick, asking Buildbucket for the build status
[00:25:04.641] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:04.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:65:0) after 1m0s
[00:26:04.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:65:0)
[00:26:04.666] Timer tick, asking Buildbucket for the build status
[00:26:04.998] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:04.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:66:0) after 1m0s
[00:27:04.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:66:0)
[00:27:04.993] Timer tick, asking Buildbucket for the build status
[00:27:05.459] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:05.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:67:0) after 1m0s
[00:28:05.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:67:0)
[00:28:05.481] Timer tick, asking Buildbucket for the build status
[00:28:05.654] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:05.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:68:0) after 1m0s
[00:29:05.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:68:0)
[00:29:05.677] Timer tick, asking Buildbucket for the build status
[00:29:05.988] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:05.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:69:0) after 1m0s
[00:30:06.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:69:0)
[00:30:06.012] Timer tick, asking Buildbucket for the build status
[00:30:06.149] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:06.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:70:0) after 1m0s
[00:31:06.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:70:0)
[00:31:06.147] Timer tick, asking Buildbucket for the build status
[00:31:06.881] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:06.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:71:0) after 1m0s
[00:32:06.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:71:0)
[00:32:06.911] Timer tick, asking Buildbucket for the build status
[00:32:07.085] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:72:0) after 1m0s
[00:33:07.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:72:0)
[00:33:07.087] Timer tick, asking Buildbucket for the build status
[00:33:07.203] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:73:0) after 1m0s
[00:34:07.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:73:0)
[00:34:07.227] Timer tick, asking Buildbucket for the build status
[00:34:07.522] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:07.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:74:0) after 1m0s
[00:35:07.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:74:0)
[00:35:07.545] Timer tick, asking Buildbucket for the build status
[00:35:07.799] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:75:0) after 1m0s
[00:36:07.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:75:0)
[00:36:07.844] Timer tick, asking Buildbucket for the build status
[00:36:08.335] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:08.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:76:0) after 1m0s
[00:37:08.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:76:0)
[00:37:08.357] Timer tick, asking Buildbucket for the build status
[00:37:08.710] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:08.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:77:0) after 1m0s
[00:38:08.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:77:0)
[00:38:08.736] Timer tick, asking Buildbucket for the build status
[00:38:08.855] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:08.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:78:0) after 1m0s
[00:39:08.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:78:0)
[00:39:08.836] Timer tick, asking Buildbucket for the build status
[00:39:09.182] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:09.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:79:0) after 1m0s
[00:40:09.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:79:0)
[00:40:09.201] Timer tick, asking Buildbucket for the build status
[00:40:10.099] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:10.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:80:0) after 1m0s
[00:41:10.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:80:0)
[00:41:10.126] Timer tick, asking Buildbucket for the build status
[00:41:10.357] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:10.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:81:0) after 1m0s
[00:42:10.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:81:0)
[00:42:10.401] Timer tick, asking Buildbucket for the build status
[00:42:11.187] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:11.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:82:0) after 1m0s
[00:43:11.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:82:0)
[00:43:11.214] Timer tick, asking Buildbucket for the build status
[00:43:11.396] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:11.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:83:0) after 1m0s
[00:44:11.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:83:0)
[00:44:11.405] Timer tick, asking Buildbucket for the build status
[00:44:11.870] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:11.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:84:0) after 1m0s
[00:45:11.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:84:0)
[00:45:11.891] Timer tick, asking Buildbucket for the build status
[00:45:12.012] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:12.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:85:0) after 1m0s
[00:46:12.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:85:0)
[00:46:12.044] Timer tick, asking Buildbucket for the build status
[00:46:12.685] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:12.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:86:0) after 1m0s
[00:47:12.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:86:0)
[00:47:12.712] Timer tick, asking Buildbucket for the build status
[00:47:12.963] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:12.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:87:0) after 1m0s
[00:48:12.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:87:0)
[00:48:12.989] Timer tick, asking Buildbucket for the build status
[00:48:13.210] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:13.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:88:0) after 1m0s
[00:49:13.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:88:0)
[00:49:13.235] Timer tick, asking Buildbucket for the build status
[00:49:14.163] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:14.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:89:0) after 1m0s
[00:50:14.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:89:0)
[00:50:14.199] Timer tick, asking Buildbucket for the build status
[00:50:14.926] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:14.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:90:0) after 1m0s
[00:51:14.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:90:0)
[00:51:14.951] Timer tick, asking Buildbucket for the build status
[00:51:15.044] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:15.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:91:0) after 1m0s
[00:52:15.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:91:0)
[00:52:15.070] Timer tick, asking Buildbucket for the build status
[00:52:16.166] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:16.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:92:0) after 1m0s
[00:53:16.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:92:0)
[00:53:16.203] Timer tick, asking Buildbucket for the build status
[00:53:16.296] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:16.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:93:0) after 1m0s
[00:54:16.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:93:0)
[00:54:16.320] Timer tick, asking Buildbucket for the build status
[00:54:16.817] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:16.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:94:0) after 1m0s
[00:55:16.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:94:0)
[00:55:16.839] Timer tick, asking Buildbucket for the build status
[00:55:17.015] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:17.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:95:0) after 1m0s
[00:56:17.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:95:0)
[00:56:17.001] Timer tick, asking Buildbucket for the build status
[00:56:17.462] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:17.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:96:0) after 1m0s
[00:57:17.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:96:0)
[00:57:17.485] Timer tick, asking Buildbucket for the build status
[00:57:17.735] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:17.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:97:0) after 1m0s
[00:58:17.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:97:0)
[00:58:17.719] Timer tick, asking Buildbucket for the build status
[00:58:18.387] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:18.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:98:0) after 1m0s
[00:59:18.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:98:0)
[00:59:18.400] Timer tick, asking Buildbucket for the build status
[00:59:18.528] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:18.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:99:0) after 1m0s
[01:00:18.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:99:0)
[01:00:18.526] Timer tick, asking Buildbucket for the build status
[01:00:18.664] Build 8917247234750781296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082677297872278336:100:0) after 1m0s
[01:00:22.954] Received PubSub notification, asking Buildbucket for the build status
[01:00:23.883] Build 8917247234750781296: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:00:23.883] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554253221782112",
"created_by": "project:chromium",
"created_ts": "1554247361381328",
"failure_reason": "BUILD_FAILURE",
"id": "8917247234750781296",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"311ef5ce0538468dd5da72ade0b5667ebd822187\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_single_process_mash_browser_tests', 'viz_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11663, \"got_angle_revision\": \"66ae531590aeef40f8d87723373e8e95145b7093\", \"got_dawn_revision\": \"20b0c33913e45cd3d553b42a43b75b27af817833\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"311ef5ce0538468dd5da72ade0b5667ebd822187\", \"got_revision_cp\": \"refs/heads/master@{#647015}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"2884953d9fbc2ddb4dc8b3dd1a6246601a834121\", \"got_v8_revision_cp\": \"refs/heads/7.5.164@{#1}\", \"got_webrtc_revision\": \"d98cbd8f91a3d94c08e3154ec78c2e54dbd3564e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27421}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"311ef5ce0538468dd5da72ade0b5667ebd822187\", \"swarm_hashes\": {\"accessibility_unittests\": \"c438b932d59e95295dd9972a52f89e986a020e38\", \"angle_unittests\": \"f0442146b329d3984c976c31c0b807d2ed504e2b\", \"app_list_unittests\": \"16bc95af48f3b45bbca6855be52246da688963b9\", \"app_shell_unittests\": \"28d0efdbefa94bfcc662345a1b49a76c5f4f8d4f\", \"ash_unittests\": \"f08979b8451982ea132ac8d018ef1ce0041370be\", \"aura_unittests\": \"9f263738f92af0d0356084dfcee787f59b5311c6\", \"base_unittests\": \"eff28831411147d692d597c9c4b3981600e96403\", \"blink_common_unittests\": \"2f9d4baa8962ba84719883668b101c0353430165\", \"blink_fuzzer_unittests\": \"6ecd8f3276ff1eff0acf68098b804ac3ad4e5d38\", \"blink_heap_unittests\": \"1c53281a6c5743fe59d904c7f17f727a53d177c3\", \"blink_platform_unittests\": \"f337ae2fb0de4eb60bc997133e6cfa3ecf08d6ab\", \"blink_unittests\": \"fb84fd6fedda8cbd481a7aecd306151ba2554768\", \"boringssl_crypto_tests\": \"2996a5a411b61f09614e8b093d45c52fee293c89\", \"boringssl_ssl_tests\": \"eca15570f848d2a1976db6ec4f4482174048f596\", \"browser_tests\": \"4cfd7f8d847491c896b7f197c46bac3ff6bcc35a\", \"cacheinvalidation_unittests\": \"8358de7dac30a590be542cd7c6122bee9acf5cf7\", \"capture_unittests\": \"3a707af40b4c87ea6146d1d9958da15cf57794d3\", \"cast_unittests\": \"5d0344dbcbcd344698bad44a84e6a4d561ddcb81\", \"cc_unittests\": \"6f6a051fbc4921cd31f84ed908658501cd374605\", \"chrome_app_unittests\": \"d4c781f294647dd0d521509ec3521abcfc221d87\", \"chromedriver_unittests\": \"407a9006176e09687ae3022b9c4c984dab5dec59\", \"chromeos_components_unittests\": \"545b7a1220695583a243fa0dd28e3ca8b7c2e4b4\", \"chromeos_unittests\": \"acd6ed44e7b26b2ad06ef2d1b7efb583d3a5f56f\", \"components_browsertests\": \"95aaa402e57482b7eb740537ad35b2794c29a6ab\", \"components_unittests\": \"f20d16f861f1ac89866e95a85176af92210421d6\", \"compositor_unittests\": \"8f8ed06164c5c755f3c1b39a32b2debbdba22dd9\", \"content_browsertests\": \"2eb4eac8917931dfa943592086a41ca74178cb9d\", \"content_unittests\": \"a9f0b18ebd47a74ee101d9e22856423831902744\", \"crypto_unittests\": \"00898c9bfb839287474e78ad36848ec0d36ddc32\", \"dbus_unittests\": \"ffa35b997b73f1b83602d35e1d27d570593b150d\", \"device_unittests\": \"cb472b699a36b974e73e63a8d5b3d06c51ece417\", \"display_unittests\": \"b6aaee07a921dcd8509fd514e188475072222555\", \"events_unittests\": \"5ab277de26f13af800766ba6550008780d2ce7a2\", \"exo_unittests\": \"ad0ccea7fef3ae4f8ffaa2661d6bdb7c99329c91\", \"extensions_browsertests\": \"0eebaa0529013573c239226599dbd01a34c301d0\", \"extensions_unittests\": \"faf91d139445c8b6fb063aa9ca13debef8e70dd6\", \"filesystem_service_unittests\": \"8c9591fb5933a04903f12da35e8ee751ee56c54d\", \"gcm_unit_tests\": \"5131f22ccc6b9df64ce40bf3fef03c0686ebf757\", \"gfx_unittests\": \"0f604c451fadfe77919007ad1324d3e9a6df5881\", \"gin_unittests\": \"b824b04a20d6de04f9d183099b5cfb18a5fa65ce\", \"gl_unittests_ozone\": \"64f725f33ddda19ebf52a52e54cbba33b7308c4e\", \"google_apis_unittests\": \"684bcbc2d65b00a4e785f49efe9c1467ab80a0e8\", \"gpu_unittests\": \"912a4251a7a1b495da069ecd9e014c745a11903f\", \"interactive_ui_tests\": \"8b7181005f596d4495b76a00cd9ca10f518701c0\", \"ipc_tests\": \"1f010ba5301f4a5bc3fbfdfa433fc16453a8c61e\", \"jingle_unittests\": \"2a733c615a569786879c5a2a1f0f9455ef60018f\", \"keyboard_unittests\": \"6a20dd23555dfdf5fecdda7517b8436bea7957f8\", \"latency_unittests\": \"4af025bdf58f32a480a08435dd36bea56043238e\", \"leveldb_service_unittests\": \"d8e8fd2a7e872770d2095aa75517b5784276e598\", \"libjingle_xmpp_unittests\": \"a2f47f1bf807571a938d83594efb9da293b3acdd\", \"media_blink_unittests\": \"815f4ee020c40f077aa399d603ab0afcd0cfa680\", \"media_service_unittests\": \"3f99d1530bfaa3ba878b49cae512e13c66fa5654\", \"media_unittests\": \"4caf4b4266c8afb4de01a75505264d41183295cc\", \"message_center_unittests\": \"4243165effb3a41298b7d4936fc87211cbb1e10b\", \"midi_unittests\": \"753499d0962ebf8724748e7606d18767fa4dd5ed\", \"mojo_core_unittests\": \"5f968af3a6c99c4427857bc37974e74295162a5e\", \"mojo_unittests\": \"beee07404349425b9c351be82331af6cde6f3d87\", \"nacl_helper_nonsfi_unittests\": \"e18fd85e3d5796ef87ec22ec4de791e411919ab3\", \"nacl_loader_unittests\": \"8f3b6beb858adb6a8c552f528a9179370c06ef70\", \"native_theme_unittests\": \"9bfc1d06d8db8f1bbe1260c0fb7d381e07602e03\", \"net_unittests\": \"696acdf0736eca76886f855cbc6258c975ade486\", \"ozone_gl_unittests\": \"a1904c0573139e93fe8bf2a244b0e4081a70da60\", \"ozone_unittests\": \"fcd0e83b9feab2761d38988e5f5cd2d726beb465\", \"ozone_x11_unittests\": \"1b4e826d5692c8b7d0d84b7a6ce6abad6b3bd3b4\", \"pdf_unittests\": \"b7886a6ae63943f7dab098b57dedcc31e17be3fd\", \"perfetto_unittests\": \"fbbada1561df5e02e206e5261f2c470705f49e8e\", \"ppapi_unittests\": \"84dea6aa0dd2eb26a73a620e026d68bf6de7e4a1\", \"printing_unittests\": \"94bb54d39740bdc0fdd289c10d2dde6a6b86e626\", \"remoting_unittests\": \"df354899e898ea09d583e5b2d9d18981f7f33e76\", \"sandbox_linux_unittests\": \"4fb3b86cb06b738cd8acc4571ed0829048746da3\", \"service_manager_unittests\": \"b6da434cb155a82238607fe3142d9580d711d3de\", \"services_unittests\": \"2cd2bcc9b54d83c947eaefe24441fe8b4d7bbe1e\", \"shell_dialogs_unittests\": \"5d6d41625c3e1f80e44e5598a61b7370673166c2\", \"skia_unittests\": \"6d8665c9fa256e8d9048fd9d3619c5520e4153d7\", \"snapshot_unittests\": \"172682900921314508a82b20230227c6d90608a8\", \"sql_unittests\": \"f2422e6d6cb3f70a27e3a338584f6c750ef2bdc0\", \"storage_unittests\": \"c0d2aedb5a083bdd1b36a25deaa59d2301eed828\", \"sync_integration_tests\": \"9e565e336f326d191094085cbd83aeb83a013ebd\", \"traffic_annotation_auditor_unittests\": \"9b344d03bfad99a05dbe3d20990320451cd6cfc1\", \"ui_base_unittests\": \"c68fc43ae934882a40f5da8e5800eb5c205ea275\", \"ui_chromeos_unittests\": \"92e6964c7d94c6dae6daba349b3a79aace9cf293\", \"ui_touch_selection_unittests\": \"87d719f216c2749505343752363b1fcf7dd60fc9\", \"unit_tests\": \"261e9c65a9b29804ec6f54ed69251978fe65a473\", \"url_unittests\": \"d650def2f929028ffd0f40ef9926873e87360762\", \"usage_time_limit_unittests\": \"f9f3ea9ab859f2ee1aa944067cac1e01e84ac8ef\", \"views_unittests\": \"391c8744eb4b33982cf93475c9e0ca669ab6155f\", \"viz_unittests\": \"6c4595e47bb708448f864b7da33d3c62d8395c32\", \"wm_unittests\": \"1e3951c073bb54957dc6f1904ea9e89ba73446f4\", \"wtf_unittests\": \"1fcea00e2ea526fa0f4d89f850675425cdcf60df\"}, \"swarm_hashes_refs/heads/master(at){#647015}_without_patch\": {\"accessibility_unittests\": \"c438b932d59e95295dd9972a52f89e986a020e38\", \"angle_unittests\": \"f0442146b329d3984c976c31c0b807d2ed504e2b\", \"app_list_unittests\": \"16bc95af48f3b45bbca6855be52246da688963b9\", \"app_shell_unittests\": \"28d0efdbefa94bfcc662345a1b49a76c5f4f8d4f\", \"ash_unittests\": \"f08979b8451982ea132ac8d018ef1ce0041370be\", \"aura_unittests\": \"9f263738f92af0d0356084dfcee787f59b5311c6\", \"base_unittests\": \"eff28831411147d692d597c9c4b3981600e96403\", \"blink_common_unittests\": \"2f9d4baa8962ba84719883668b101c0353430165\", \"blink_fuzzer_unittests\": \"6ecd8f3276ff1eff0acf68098b804ac3ad4e5d38\", \"blink_heap_unittests\": \"1c53281a6c5743fe59d904c7f17f727a53d177c3\", \"blink_platform_unittests\": \"f337ae2fb0de4eb60bc997133e6cfa3ecf08d6ab\", \"blink_unittests\": \"fb84fd6fedda8cbd481a7aecd306151ba2554768\", \"boringssl_crypto_tests\": \"2996a5a411b61f09614e8b093d45c52fee293c89\", \"boringssl_ssl_tests\": \"eca15570f848d2a1976db6ec4f4482174048f596\", \"browser_tests\": \"4cfd7f8d847491c896b7f197c46bac3ff6bcc35a\", \"cacheinvalidation_unittests\": \"8358de7dac30a590be542cd7c6122bee9acf5cf7\", \"capture_unittests\": \"3a707af40b4c87ea6146d1d9958da15cf57794d3\", \"cast_unittests\": \"5d0344dbcbcd344698bad44a84e6a4d561ddcb81\", \"cc_unittests\": \"6f6a051fbc4921cd31f84ed908658501cd374605\", \"chrome_app_unittests\": \"d4c781f294647dd0d521509ec3521abcfc221d87\", \"chromedriver_unittests\": \"407a9006176e09687ae3022b9c4c984dab5dec59\", \"chromeos_components_unittests\": \"545b7a1220695583a243fa0dd28e3ca8b7c2e4b4\", \"chromeos_unittests\": \"acd6ed44e7b26b2ad06ef2d1b7efb583d3a5f56f\", \"components_browsertests\": \"95aaa402e57482b7eb740537ad35b2794c29a6ab\", \"components_unittests\": \"f20d16f861f1ac89866e95a85176af92210421d6\", \"compositor_unittests\": \"8f8ed06164c5c755f3c1b39a32b2debbdba22dd9\", \"content_browsertests\": \"2eb4eac8917931dfa943592086a41ca74178cb9d\", \"content_unittests\": \"a9f0b18ebd47a74ee101d9e22856423831902744\", \"crypto_unittests\": \"00898c9bfb839287474e78ad36848ec0d36ddc32\", \"dbus_unittests\": \"ffa35b997b73f1b83602d35e1d27d570593b150d\", \"device_unittests\": \"cb472b699a36b974e73e63a8d5b3d06c51ece417\", \"display_unittests\": \"b6aaee07a921dcd8509fd514e188475072222555\", \"events_unittests\": \"5ab277de26f13af800766ba6550008780d2ce7a2\", \"exo_unittests\": \"ad0ccea7fef3ae4f8ffaa2661d6bdb7c99329c91\", \"extensions_browsertests\": \"0eebaa0529013573c239226599dbd01a34c301d0\", \"extensions_unittests\": \"faf91d139445c8b6fb063aa9ca13debef8e70dd6\", \"filesystem_service_unittests\": \"8c9591fb5933a04903f12da35e8ee751ee56c54d\", \"gcm_unit_tests\": \"5131f22ccc6b9df64ce40bf3fef03c0686ebf757\", \"gfx_unittests\": \"0f604c451fadfe77919007ad1324d3e9a6df5881\", \"gin_unittests\": \"b824b04a20d6de04f9d183099b5cfb18a5fa65ce\", \"gl_unittests_ozone\": \"64f725f33ddda19ebf52a52e54cbba33b7308c4e\", \"google_apis_unittests\": \"684bcbc2d65b00a4e785f49efe9c1467ab80a0e8\", \"gpu_unittests\": \"912a4251a7a1b495da069ecd9e014c745a11903f\", \"interactive_ui_tests\": \"8b7181005f596d4495b76a00cd9ca10f518701c0\", \"ipc_tests\": \"1f010ba5301f4a5bc3fbfdfa433fc16453a8c61e\", \"jingle_unittests\": \"2a733c615a569786879c5a2a1f0f9455ef60018f\", \"keyboard_unittests\": \"6a20dd23555dfdf5fecdda7517b8436bea7957f8\", \"latency_unittests\": \"4af025bdf58f32a480a08435dd36bea56043238e\", \"leveldb_service_unittests\": \"d8e8fd2a7e872770d2095aa75517b5784276e598\", \"libjingle_xmpp_unittests\": \"a2f47f1bf807571a938d83594efb9da293b3acdd\", \"media_blink_unittests\": \"815f4ee020c40f077aa399d603ab0afcd0cfa680\", \"media_service_unittests\": \"3f99d1530bfaa3ba878b49cae512e13c66fa5654\", \"media_unittests\": \"4caf4b4266c8afb4de01a75505264d41183295cc\", \"message_center_unittests\": \"4243165effb3a41298b7d4936fc87211cbb1e10b\", \"midi_unittests\": \"753499d0962ebf8724748e7606d18767fa4dd5ed\", \"mojo_core_unittests\": \"5f968af3a6c99c4427857bc37974e74295162a5e\", \"mojo_unittests\": \"beee07404349425b9c351be82331af6cde6f3d87\", \"nacl_helper_nonsfi_unittests\": \"e18fd85e3d5796ef87ec22ec4de791e411919ab3\", \"nacl_loader_unittests\": \"8f3b6beb858adb6a8c552f528a9179370c06ef70\", \"native_theme_unittests\": \"9bfc1d06d8db8f1bbe1260c0fb7d381e07602e03\", \"net_unittests\": \"696acdf0736eca76886f855cbc6258c975ade486\", \"ozone_gl_unittests\": \"a1904c0573139e93fe8bf2a244b0e4081a70da60\", \"ozone_unittests\": \"fcd0e83b9feab2761d38988e5f5cd2d726beb465\", \"ozone_x11_unittests\": \"1b4e826d5692c8b7d0d84b7a6ce6abad6b3bd3b4\", \"pdf_unittests\": \"b7886a6ae63943f7dab098b57dedcc31e17be3fd\", \"perfetto_unittests\": \"fbbada1561df5e02e206e5261f2c470705f49e8e\", \"ppapi_unittests\": \"84dea6aa0dd2eb26a73a620e026d68bf6de7e4a1\", \"printing_unittests\": \"94bb54d39740bdc0fdd289c10d2dde6a6b86e626\", \"remoting_unittests\": \"df354899e898ea09d583e5b2d9d18981f7f33e76\", \"sandbox_linux_unittests\": \"4fb3b86cb06b738cd8acc4571ed0829048746da3\", \"service_manager_unittests\": \"b6da434cb155a82238607fe3142d9580d711d3de\", \"services_unittests\": \"2cd2bcc9b54d83c947eaefe24441fe8b4d7bbe1e\", \"shell_dialogs_unittests\": \"5d6d41625c3e1f80e44e5598a61b7370673166c2\", \"skia_unittests\": \"6d8665c9fa256e8d9048fd9d3619c5520e4153d7\", \"snapshot_unittests\": \"172682900921314508a82b20230227c6d90608a8\", \"sql_unittests\": \"f2422e6d6cb3f70a27e3a338584f6c750ef2bdc0\", \"storage_unittests\": \"c0d2aedb5a083bdd1b36a25deaa59d2301eed828\", \"sync_integration_tests\": \"9e565e336f326d191094085cbd83aeb83a013ebd\", \"traffic_annotation_auditor_unittests\": \"9b344d03bfad99a05dbe3d20990320451cd6cfc1\", \"ui_base_unittests\": \"c68fc43ae934882a40f5da8e5800eb5c205ea275\", \"ui_chromeos_unittests\": \"92e6964c7d94c6dae6daba349b3a79aace9cf293\", \"ui_touch_selection_unittests\": \"87d719f216c2749505343752363b1fcf7dd60fc9\", \"unit_tests\": \"261e9c65a9b29804ec6f54ed69251978fe65a473\", \"url_unittests\": \"d650def2f929028ffd0f40ef9926873e87360762\", \"usage_time_limit_unittests\": \"f9f3ea9ab859f2ee1aa944067cac1e01e84ac8ef\", \"views_unittests\": \"391c8744eb4b33982cf93475c9e0ca669ab6155f\", \"viz_unittests\": \"6c4595e47bb708448f864b7da33d3c62d8395c32\", \"wm_unittests\": \"1e3951c073bb54957dc6f1904ea9e89ba73446f4\", \"wtf_unittests\": \"1fcea00e2ea526fa0f4d89f850675425cdcf60df\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4237-a5bdaf4\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"5 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_single_process_mash_browser_tests', 'viz_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554247670782834",
"status": "COMPLETED",
"status_changed_ts": "1554247671094548",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11663",
"builder:linux-chromeos-dbg",
"buildset:commit/git/311ef5ce0538468dd5da72ade0b5667ebd822187",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/311ef5ce0538468dd5da72ade0b5667ebd822187",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082677297872278336",
"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/8917247234750781296/+/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:43f92e2e1c259310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554253222648861",
"url": "https://ci.chromium.org/b/8917247234750781296",
"utcnow_ts": "1554253223858324"
}
}
[01:00:23.883] Invocation finished in 1h37m44.861773004s with status FAILED