[22:33:43.631] New invocation is queued and will start shortly
[22:33:44.793] Starting the invocation (attempt 1)
[22:33:44.887] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:33:44.887] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:33:44.887] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028322196028490816",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd88b6a26582f86732979863f07e852a22940be2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028322196028490816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/dd88b6a26582f86732979863f07e852a22940be2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd88b6a26582f86732979863f07e852a22940be2",
"gitiles_ref:refs/heads/master"
]
}
[22:33:45.501] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606084424974478",
"id": "8862892133956929328",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd88b6a26582f86732979863f07e852a22940be2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606084425366215",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43061",
"builder:linux-chromeos-rel",
"buildset:commit/git/dd88b6a26582f86732979863f07e852a22940be2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd88b6a26582f86732979863f07e852a22940be2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028322196028490816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862892133956929328/+/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": "1606084425366362",
"url": "https://ci.chromium.org/b/8862892133956929328",
"utcnow_ts": "1606084425490825"
}
}
[22:33:45.501] Task URL: https://ci.chromium.org/b/8862892133956929328
[22:33:45.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:2:0) after 1m0s
[22:34:45.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:2:0)
[22:34:45.523] Timer tick, asking Buildbucket for the build status
[22:34:45.637] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:45.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:3:0) after 1m0s
[22:35:45.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:3:0)
[22:35:45.637] Timer tick, asking Buildbucket for the build status
[22:35:45.717] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:45.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:4:0) after 1m0s
[22:36:45.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:4:0)
[22:36:45.763] Timer tick, asking Buildbucket for the build status
[22:36:45.908] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:45.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:5:0) after 1m0s
[22:37:45.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:5:0)
[22:37:45.983] Timer tick, asking Buildbucket for the build status
[22:37:46.076] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:46.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:6:0) after 1m0s
[22:38:46.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:6:0)
[22:38:46.143] Timer tick, asking Buildbucket for the build status
[22:38:46.230] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:46.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:7:0) after 1m0s
[22:39:46.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:7:0)
[22:39:46.243] Timer tick, asking Buildbucket for the build status
[22:39:46.525] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:46.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:8:0) after 1m0s
[22:40:46.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:8:0)
[22:40:46.609] Timer tick, asking Buildbucket for the build status
[22:40:46.737] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:46.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:9:0) after 1m0s
[22:41:46.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:9:0)
[22:41:46.816] Timer tick, asking Buildbucket for the build status
[22:41:46.905] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:46.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:10:0) after 1m0s
[22:42:46.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:10:0)
[22:42:46.953] Timer tick, asking Buildbucket for the build status
[22:42:47.052] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:47.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:11:0) after 1m0s
[22:43:47.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:11:0)
[22:43:47.074] Timer tick, asking Buildbucket for the build status
[22:43:47.202] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:47.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:12:0) after 1m0s
[22:44:47.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:12:0)
[22:44:47.270] Timer tick, asking Buildbucket for the build status
[22:44:47.352] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:47.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:13:0) after 1m0s
[22:45:47.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:13:0)
[22:45:47.391] Timer tick, asking Buildbucket for the build status
[22:45:47.453] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:47.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:14:0) after 1m0s
[22:46:47.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:14:0)
[22:46:47.477] Timer tick, asking Buildbucket for the build status
[22:46:47.560] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:15:0) after 1m0s
[22:47:47.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:15:0)
[22:47:47.622] Timer tick, asking Buildbucket for the build status
[22:47:47.752] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:47.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:16:0) after 1m0s
[22:48:47.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:16:0)
[22:48:47.813] Timer tick, asking Buildbucket for the build status
[22:48:47.930] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:17:0) after 1m0s
[22:49:48.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:17:0)
[22:49:48.005] Timer tick, asking Buildbucket for the build status
[22:49:48.087] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:48.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:18:0) after 1m0s
[22:50:48.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:18:0)
[22:50:48.107] Timer tick, asking Buildbucket for the build status
[22:50:48.179] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:48.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:19:0) after 1m0s
[22:51:48.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:19:0)
[22:51:48.243] Timer tick, asking Buildbucket for the build status
[22:51:48.313] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:48.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:20:0) after 1m0s
[22:52:48.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:20:0)
[22:52:48.375] Timer tick, asking Buildbucket for the build status
[22:52:48.480] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:48.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:21:0) after 1m0s
[22:53:48.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:21:0)
[22:53:48.504] Timer tick, asking Buildbucket for the build status
[22:53:48.589] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:48.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:22:0) after 1m0s
[22:54:48.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:22:0)
[22:54:48.660] Timer tick, asking Buildbucket for the build status
[22:54:48.735] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:48.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:23:0) after 1m0s
[22:55:48.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:23:0)
[22:55:48.861] Timer tick, asking Buildbucket for the build status
[22:55:49.102] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:49.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:24:0) after 1m0s
[22:56:49.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:24:0)
[22:56:49.166] Timer tick, asking Buildbucket for the build status
[22:56:49.390] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:49.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:25:0) after 1m0s
[22:57:49.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:25:0)
[22:57:49.419] Timer tick, asking Buildbucket for the build status
[22:57:49.653] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:49.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:26:0) after 1m0s
[22:58:49.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:26:0)
[22:58:49.759] Timer tick, asking Buildbucket for the build status
[22:58:49.965] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:49.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:27:0) after 1m0s
[22:59:50.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:27:0)
[22:59:50.028] Timer tick, asking Buildbucket for the build status
[22:59:50.123] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:50.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:28:0) after 1m0s
[23:00:50.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:28:0)
[23:00:50.182] Timer tick, asking Buildbucket for the build status
[23:00:50.377] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:29:0) after 1m0s
[23:01:50.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:29:0)
[23:01:50.413] Timer tick, asking Buildbucket for the build status
[23:01:50.521] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:50.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:30:0) after 1m0s
[23:02:50.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:30:0)
[23:02:50.640] Timer tick, asking Buildbucket for the build status
[23:02:50.748] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:50.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:31:0) after 1m0s
[23:03:50.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:31:0)
[23:03:50.807] Timer tick, asking Buildbucket for the build status
[23:03:50.899] Build 8862892133956929328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:50.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:32:0) after 1m0s
[23:04:06.547] Received PubSub notification, asking Buildbucket for the build status
[23:04:06.612] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:32:0)
[23:04:51.037] Timer tick, asking Buildbucket for the build status
[23:04:51.137] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:34:0) after 1m0s
[23:05:51.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:34:0)
[23:05:51.141] Timer tick, asking Buildbucket for the build status
[23:05:51.242] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:51.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:35:0) after 1m0s
[23:06:51.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:35:0)
[23:06:51.271] Timer tick, asking Buildbucket for the build status
[23:06:51.448] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:36:0) after 1m0s
[23:07:51.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:36:0)
[23:07:51.538] Timer tick, asking Buildbucket for the build status
[23:07:51.608] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:51.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:37:0) after 1m0s
[23:08:51.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:37:0)
[23:08:51.673] Timer tick, asking Buildbucket for the build status
[23:08:51.781] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:51.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:38:0) after 1m0s
[23:09:51.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:38:0)
[23:09:51.808] Timer tick, asking Buildbucket for the build status
[23:09:51.872] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:51.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:39:0) after 1m0s
[23:10:51.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:39:0)
[23:10:51.948] Timer tick, asking Buildbucket for the build status
[23:10:52.003] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:52.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:40:0) after 1m0s
[23:11:52.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:40:0)
[23:11:52.128] Timer tick, asking Buildbucket for the build status
[23:11:52.258] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:52.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:41:0) after 1m0s
[23:12:52.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:41:0)
[23:12:52.369] Timer tick, asking Buildbucket for the build status
[23:12:52.489] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:52.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:42:0) after 1m0s
[23:13:52.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:42:0)
[23:13:52.622] Timer tick, asking Buildbucket for the build status
[23:13:52.902] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:52.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:43:0) after 1m0s
[23:14:52.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:43:0)
[23:14:52.949] Timer tick, asking Buildbucket for the build status
[23:14:53.009] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:53.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:44:0) after 1m0s
[23:15:53.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:44:0)
[23:15:53.084] Timer tick, asking Buildbucket for the build status
[23:15:53.144] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:53.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:45:0) after 1m0s
[23:16:53.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:45:0)
[23:16:53.182] Timer tick, asking Buildbucket for the build status
[23:16:53.283] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:53.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:46:0) after 1m0s
[23:17:53.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:46:0)
[23:17:53.368] Timer tick, asking Buildbucket for the build status
[23:17:53.465] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:47:0) after 1m0s
[23:18:53.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:47:0)
[23:18:53.574] Timer tick, asking Buildbucket for the build status
[23:18:53.678] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:53.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:48:0) after 1m0s
[23:19:53.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:48:0)
[23:19:53.743] Timer tick, asking Buildbucket for the build status
[23:19:53.838] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:53.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:49:0) after 1m0s
[23:20:53.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:49:0)
[23:20:53.994] Timer tick, asking Buildbucket for the build status
[23:20:54.115] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:54.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:50:0) after 1m0s
[23:21:54.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:50:0)
[23:21:54.195] Timer tick, asking Buildbucket for the build status
[23:21:54.260] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:54.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:51:0) after 1m0s
[23:22:54.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:51:0)
[23:22:54.380] Timer tick, asking Buildbucket for the build status
[23:22:54.549] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:52:0) after 1m0s
[23:23:54.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:52:0)
[23:23:54.594] Timer tick, asking Buildbucket for the build status
[23:23:54.798] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:54.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:53:0) after 1m0s
[23:24:54.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:53:0)
[23:24:54.837] Timer tick, asking Buildbucket for the build status
[23:24:54.903] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:54.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:54:0) after 1m0s
[23:25:54.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:54:0)
[23:25:54.942] Timer tick, asking Buildbucket for the build status
[23:25:55.032] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:55.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:55:0) after 1m0s
[23:26:55.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:55:0)
[23:26:55.192] Timer tick, asking Buildbucket for the build status
[23:26:55.348] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:55.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:56:0) after 1m0s
[23:27:55.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:56:0)
[23:27:55.410] Timer tick, asking Buildbucket for the build status
[23:27:55.871] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:55.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:57:0) after 1m0s
[23:28:55.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:57:0)
[23:28:55.932] Timer tick, asking Buildbucket for the build status
[23:28:56.049] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:56.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:58:0) after 1m0s
[23:29:56.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:58:0)
[23:29:56.121] Timer tick, asking Buildbucket for the build status
[23:29:56.181] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:56.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:59:0) after 1m0s
[23:30:56.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:59:0)
[23:30:56.266] Timer tick, asking Buildbucket for the build status
[23:30:56.578] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:56.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:60:0) after 1m0s
[23:31:56.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:60:0)
[23:31:56.704] Timer tick, asking Buildbucket for the build status
[23:31:56.775] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:56.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:61:0) after 1m0s
[23:32:56.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:61:0)
[23:32:56.855] Timer tick, asking Buildbucket for the build status
[23:32:56.909] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:56.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:62:0) after 1m0s
[23:33:56.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:62:0)
[23:33:56.932] Timer tick, asking Buildbucket for the build status
[23:33:57.087] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:57.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:63:0) after 1m0s
[23:34:57.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:63:0)
[23:34:57.129] Timer tick, asking Buildbucket for the build status
[23:34:57.516] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:64:0) after 1m0s
[23:35:57.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:64:0)
[23:35:57.649] Timer tick, asking Buildbucket for the build status
[23:35:57.748] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:57.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:65:0) after 1m0s
[23:36:57.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:65:0)
[23:36:57.769] Timer tick, asking Buildbucket for the build status
[23:36:57.877] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:57.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:66:0) after 1m0s
[23:37:57.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:66:0)
[23:37:57.862] Timer tick, asking Buildbucket for the build status
[23:37:57.922] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:57.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:67:0) after 1m0s
[23:38:57.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:67:0)
[23:38:57.918] Timer tick, asking Buildbucket for the build status
[23:38:58.036] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:58.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:68:0) after 1m0s
[23:39:58.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:68:0)
[23:39:58.063] Timer tick, asking Buildbucket for the build status
[23:39:58.208] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:58.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:69:0) after 1m0s
[23:40:58.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:69:0)
[23:40:58.233] Timer tick, asking Buildbucket for the build status
[23:40:58.298] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:58.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:70:0) after 1m0s
[23:41:58.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:70:0)
[23:41:58.404] Timer tick, asking Buildbucket for the build status
[23:41:58.528] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:58.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:71:0) after 1m0s
[23:42:58.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:71:0)
[23:42:58.552] Timer tick, asking Buildbucket for the build status
[23:42:58.672] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:58.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:72:0) after 1m0s
[23:43:58.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:72:0)
[23:43:58.701] Timer tick, asking Buildbucket for the build status
[23:43:58.806] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:58.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:73:0) after 1m0s
[23:44:58.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:73:0)
[23:44:58.834] Timer tick, asking Buildbucket for the build status
[23:44:58.904] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:58.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:74:0) after 1m0s
[23:45:58.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:74:0)
[23:45:58.951] Timer tick, asking Buildbucket for the build status
[23:45:59.263] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:59.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:75:0) after 1m0s
[23:46:59.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:75:0)
[23:46:59.285] Timer tick, asking Buildbucket for the build status
[23:46:59.434] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:59.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:76:0) after 1m0s
[23:47:59.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:76:0)
[23:47:59.553] Timer tick, asking Buildbucket for the build status
[23:47:59.785] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:59.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:77:0) after 1m0s
[23:48:59.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:77:0)
[23:48:59.896] Timer tick, asking Buildbucket for the build status
[23:49:00.185] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:00.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:78:0) after 1m0s
[23:50:00.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:78:0)
[23:50:00.236] Timer tick, asking Buildbucket for the build status
[23:50:00.678] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:00.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:79:0) after 1m0s
[23:51:01.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:79:0)
[23:51:01.269] Timer tick, asking Buildbucket for the build status
[23:51:01.417] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:01.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:80:0) after 1m0s
[23:52:01.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:80:0)
[23:52:01.503] Timer tick, asking Buildbucket for the build status
[23:52:01.716] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:01.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:81:0) after 1m0s
[23:53:01.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:81:0)
[23:53:01.921] Timer tick, asking Buildbucket for the build status
[23:53:02.110] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:02.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:82:0) after 1m0s
[23:54:02.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:82:0)
[23:54:02.228] Timer tick, asking Buildbucket for the build status
[23:54:02.375] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:02.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:83:0) after 1m0s
[23:55:02.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:83:0)
[23:55:02.530] Timer tick, asking Buildbucket for the build status
[23:55:02.598] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:02.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:84:0) after 1m0s
[23:56:02.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:84:0)
[23:56:02.766] Timer tick, asking Buildbucket for the build status
[23:56:02.849] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:02.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:85:0) after 1m0s
[23:57:03.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:85:0)
[23:57:03.011] Timer tick, asking Buildbucket for the build status
[23:57:03.357] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:03.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:86:0) after 1m0s
[23:58:03.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:86:0)
[23:58:03.401] Timer tick, asking Buildbucket for the build status
[23:58:03.488] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:03.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:87:0) after 1m0s
[23:59:03.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:87:0)
[23:59:03.550] Timer tick, asking Buildbucket for the build status
[23:59:03.947] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:03.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:88:0) after 1m0s
[00:00:03.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:88:0)
[00:00:03.960] Timer tick, asking Buildbucket for the build status
[00:00:04.195] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:04.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:89:0) after 1m0s
[00:01:04.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:89:0)
[00:01:04.332] Timer tick, asking Buildbucket for the build status
[00:01:04.393] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:04.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:90:0) after 1m0s
[00:02:04.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:90:0)
[00:02:04.447] Timer tick, asking Buildbucket for the build status
[00:02:04.726] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:04.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:91:0) after 1m0s
[00:03:04.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:91:0)
[00:03:04.830] Timer tick, asking Buildbucket for the build status
[00:03:04.938] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:04.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:92:0) after 1m0s
[00:04:04.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:92:0)
[00:04:04.995] Timer tick, asking Buildbucket for the build status
[00:04:05.148] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:05.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:93:0) after 1m0s
[00:05:05.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:93:0)
[00:05:05.200] Timer tick, asking Buildbucket for the build status
[00:05:05.356] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:05.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:94:0) after 1m0s
[00:06:05.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:94:0)
[00:06:05.414] Timer tick, asking Buildbucket for the build status
[00:06:05.476] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:05.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:95:0) after 1m0s
[00:07:05.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:95:0)
[00:07:05.576] Timer tick, asking Buildbucket for the build status
[00:07:05.639] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:05.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:96:0) after 1m0s
[00:08:05.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:96:0)
[00:08:05.711] Timer tick, asking Buildbucket for the build status
[00:08:05.867] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:05.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:97:0) after 1m0s
[00:09:05.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:97:0)
[00:09:05.951] Timer tick, asking Buildbucket for the build status
[00:09:06.077] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:06.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:98:0) after 1m0s
[00:10:06.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:98:0)
[00:10:06.149] Timer tick, asking Buildbucket for the build status
[00:10:06.251] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:06.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:99:0) after 1m0s
[00:11:06.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:99:0)
[00:11:06.282] Timer tick, asking Buildbucket for the build status
[00:11:06.539] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:06.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:100:0) after 1m0s
[00:12:06.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:100:0)
[00:12:06.563] Timer tick, asking Buildbucket for the build status
[00:12:06.623] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:06.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:101:0) after 1m0s
[00:13:06.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:101:0)
[00:13:06.663] Timer tick, asking Buildbucket for the build status
[00:13:06.735] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:06.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:102:0) after 1m0s
[00:14:06.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:102:0)
[00:14:06.883] Timer tick, asking Buildbucket for the build status
[00:14:07.002] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:07.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:103:0) after 1m0s
[00:15:07.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:103:0)
[00:15:07.103] Timer tick, asking Buildbucket for the build status
[00:15:07.229] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:07.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:104:0) after 1m0s
[00:16:07.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:104:0)
[00:16:07.316] Timer tick, asking Buildbucket for the build status
[00:16:07.647] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:07.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:105:0) after 1m0s
[00:17:07.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:105:0)
[00:17:07.679] Timer tick, asking Buildbucket for the build status
[00:17:07.787] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:07.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:106:0) after 1m0s
[00:18:07.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:106:0)
[00:18:07.846] Timer tick, asking Buildbucket for the build status
[00:18:08.215] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:08.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:107:0) after 1m0s
[00:19:08.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:107:0)
[00:19:08.272] Timer tick, asking Buildbucket for the build status
[00:19:08.426] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:108:0) after 1m0s
[00:20:08.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:108:0)
[00:20:08.452] Timer tick, asking Buildbucket for the build status
[00:20:08.737] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:08.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:109:0) after 1m0s
[00:21:08.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:109:0)
[00:21:08.771] Timer tick, asking Buildbucket for the build status
[00:21:08.902] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:08.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:110:0) after 1m0s
[00:22:09.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:110:0)
[00:22:09.040] Timer tick, asking Buildbucket for the build status
[00:22:09.276] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:09.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:111:0) after 1m0s
[00:23:09.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:111:0)
[00:23:09.347] Timer tick, asking Buildbucket for the build status
[00:23:09.538] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:09.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:112:0) after 1m0s
[00:24:09.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:112:0)
[00:24:09.596] Timer tick, asking Buildbucket for the build status
[00:24:09.953] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:09.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:113:0) after 1m0s
[00:25:10.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:113:0)
[00:25:10.025] Timer tick, asking Buildbucket for the build status
[00:25:10.116] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:10.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:114:0) after 1m0s
[00:26:10.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:114:0)
[00:26:10.150] Timer tick, asking Buildbucket for the build status
[00:26:10.362] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:10.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:115:0) after 1m0s
[00:27:10.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:115:0)
[00:27:10.460] Timer tick, asking Buildbucket for the build status
[00:27:10.695] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:10.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:116:0) after 1m0s
[00:28:10.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:116:0)
[00:28:10.773] Timer tick, asking Buildbucket for the build status
[00:28:11.431] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:11.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:117:0) after 1m0s
[00:29:11.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:117:0)
[00:29:11.429] Timer tick, asking Buildbucket for the build status
[00:29:11.530] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:11.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:118:0) after 1m0s
[00:30:11.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:118:0)
[00:30:11.570] Timer tick, asking Buildbucket for the build status
[00:30:12.033] Build 8862892133956929328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:12.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028322196028490816:119:0) after 1m0s
[00:30:48.694] Received PubSub notification, asking Buildbucket for the build status
[00:30:48.783] Build 8862892133956929328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:30:48.783] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606091447656418",
"created_by": "project:chromium",
"created_ts": "1606084424974478",
"id": "8862892133956929328",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd88b6a26582f86732979863f07e852a22940be2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"dd88b6a26582f86732979863f07e852a22940be2\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-129-zr64\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43061, \"got_angle_revision\": \"ebf890fcf9f3e297dcb0736b31bce3e63d63c4d7\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"dd88b6a26582f86732979863f07e852a22940be2\", \"got_revision_cp\": \"refs/heads/master@{#830061}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"805e8b5987e5fd07adbe998f0c21f84cb20468b8\", \"got_v8_revision_cp\": \"refs/heads/8.9.32@{#1}\", \"got_webrtc_revision\": \"c5fd745672798221b71611a92c33ab5f2bf04a3b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32663}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd88b6a26582f86732979863f07e852a22940be2\", \"swarm_hashes_refs/heads/master(at){#830061}_without_patch\": {\"absl_hardening_tests\": \"1275f5b9dc7d3b2cf668af4b1e5dd0b0802fd348\", \"accessibility_unittests\": \"49327800766d501393985b12d04d7923bacaa893\", \"angle_unittests\": \"3db6269bca9557533de33ba1bf85681db4e3f959\", \"app_list_unittests\": \"454ea166fd0fbe32caa156ef89911e70148833fc\", \"app_shell_unittests\": \"d9fc0aee1fc39dfb4d0033a891e61c3c30fdf4e7\", \"ash_unittests\": \"d251c9a46ae2777b391972d8d2f508810a17ab35\", \"aura_unittests\": \"7610bc138474df483a1d4098a1b480842559456a\", \"base_unittests\": \"0717324579b2d46e293d5c5fee32e0f9e0831ed9\", \"base_util_unittests\": \"0639181a0c5a7521769fed74dfecbcef59efc9dc\", \"blink_common_unittests\": \"2678d9f6bfcd736452c88129e9d36a268b6e4dd5\", \"blink_fuzzer_unittests\": \"cfdb9203804320e75ae883c9122890d120759c89\", \"blink_heap_unittests\": \"c4d4b5aa8956fb04ae1250538579e7bcc2eda732\", \"blink_platform_unittests\": \"820bc69302ffa509a9a8bc6e1724b0c417f4e591\", \"blink_unittests\": \"11623e06a9cf1b48111ca1c34e9d965bad6c7336\", \"boringssl_crypto_tests\": \"8fd5161edf0c75d2bbef10080e86312301e4a7a3\", \"boringssl_ssl_tests\": \"4f47fed6a41556e792b7cc407473d2c34df24c09\", \"browser_tests\": \"a793ebf3487a21bb9ddfe8a2c72b9910b36c728e\", \"capture_unittests\": \"91dba95b35c91d752d0627aca7451baa0d0c72e5\", \"cast_unittests\": \"19668a8ffb9308cab9bfb7ccdab5d7bc5f3c4be6\", \"cc_unittests\": \"214db31fdef3560f68c167a78a6ea2357650969d\", \"chrome_app_unittests\": \"bfbea071dc7ca6119c0ad861cff2c3f295a61798\", \"chromedriver_unittests\": \"91284d21c9ffd6a3ba977041013995e9ba7ce569\", \"chromeos_components_unittests\": \"1f17fcd4ca418ee0227208b093273aba8dcae19a\", \"chromeos_unittests\": \"9f1decc8bb0054025b1cda15bb9578138c6578c6\", \"components_browsertests\": \"30359dbaed91689db92304173c2018876e42ddb7\", \"components_unittests\": \"5b1e2fd1bc92509f8bc9ebc3424afea29229f426\", \"compositor_unittests\": \"7147bd2bb5d70b9824c8dcb79db637cbfe1af328\", \"content_browsertests\": \"24dc3f600527cfa9f61a5e6b9574f4c63a438f07\", \"content_unittests\": \"72a7d8d96be6b0135e539a4279cd9201a7999fb7\", \"crashpad_tests\": \"a193d3c8fd977b59691811972fcfe8c75ab98331\", \"crypto_unittests\": \"ffbea861ff40ca63557bf9e0dbdece399f089f71\", \"dbus_unittests\": \"b30fda846fb981fced9fe364e0f5741785b30239\", \"device_unittests\": \"e82cabaea6de4125e57486f945d7ef381f0ac348\", \"display_unittests\": \"43102dc2418d4ec5f2479a2ea3c97917a4de4749\", \"events_unittests\": \"9381c4ca2aca4eb11faa795f5b7354894b4fd179\", \"exo_unittests\": \"7f72de74668f7f7d7e8f4ad6d89c96b8b7e22e2d\", \"extensions_browsertests\": \"396248716a6d36e324dc01a23f65ace98713b1f1\", \"extensions_unittests\": \"7959295548ddf1d4f27f1f4fc666055699ddc92f\", \"filesystem_service_unittests\": \"2f1f45f343fef805081edcedd48b5b574042e5e5\", \"gcm_unit_tests\": \"9478d4ab66729189c66f5dad4c828729b5d8cf2d\", \"gfx_unittests\": \"9151654e972c1980cbfeee0ff95e7e32c1c44a02\", \"gin_unittests\": \"616eb73c094d76c5e1b1cd406a6c4a59bc9222c1\", \"gl_unittests_ozone\": \"2693b8b2d394dc3e92f687c9b769cfc9dc14279b\", \"google_apis_unittests\": \"3d46f3b6f82064ce8b2d5b4189e83daaca9edb7a\", \"gpu_unittests\": \"8e7e690a3773ae689da11c950f757337572ee2c0\", \"gwp_asan_unittests\": \"d65b1d2535acc5e12b11ac1f06edeaabbd0199d7\", \"interactive_ui_tests\": \"64b915c6dc51e4e4bc69c9cbd4057359669ee126\", \"ipc_tests\": \"7b805138acd41f306e0deb0f03372d607626c340\", \"jingle_unittests\": \"9bb00f4cf6b28097093916a0d49903ea0fe6131a\", \"keyboard_unittests\": \"9044b5d08645ab290b1b98e5e6977fba58b1c345\", \"latency_unittests\": \"ce9a2c9f8b8bd7008d55bb4eacbb31b71927ddf3\", \"libjingle_xmpp_unittests\": \"b5db31201688aef291126f97c63d9709e9f70629\", \"liburlpattern_unittests\": \"c4f95f31208321e19a0917611331c145f1753f84\", \"media_blink_unittests\": \"44901745cccca0bf41962e03c6a844e5e0180527\", \"media_unittests\": \"a2adeb97f620b2751d9a15cc4ae7f1f08d8b1778\", \"message_center_unittests\": \"80b31001112877ec06795c4108bdd7c495fd90de\", \"midi_unittests\": \"75adc5694b22900af5ede6584d24a2f60ba48b43\", \"mojo_core_unittests\": \"e96594339dc2b1f7613fe2e58f2e213c5683ac70\", \"mojo_unittests\": \"f8b4ddd6db467a93dd3b591ef1ef769fb6e0e7d6\", \"nacl_helper_nonsfi_unittests\": \"0c529112e84422506f83264a7850ab1510f252a0\", \"nacl_loader_unittests\": \"b3b4d66c625adf9621d6c6560eefe6ee1b7cfd26\", \"native_theme_unittests\": \"7a554ffd03c7557f395f351a77862caf7d143532\", \"net_unittests\": \"b989c4c77c5fd639f4c1128097707862a4b11dd9\", \"ozone_gl_unittests\": \"cbabc31877c5de58e19a613357dcea1a0d6550bc\", \"ozone_unittests\": \"52660061af650da8a81db1da0d1625da8707038e\", \"ozone_x11_unittests\": \"4f42d47c071b9a34e7141c9662de63e469ddf536\", \"pdf_unittests\": \"3809564315b5de9931b0c0323141d8247016083d\", \"perfetto_unittests\": \"0e17c8f024261b8610ac61a4acb8aa0d6337da8f\", \"ppapi_unittests\": \"6ac35dd07ee86d644b272ca4c483b89b05ee063f\", \"printing_unittests\": \"d5c26e59062453eb9b7fa10f52f2b8eae84cca43\", \"remoting_unittests\": \"3a8efadf7289077cb2a4a0277ae7b5f930871baf\", \"sandbox_linux_unittests\": \"e6f29da3918a2b546731c585ec11a6845e2751de\", \"service_manager_unittests\": \"51f430f3378bd40fd0ada00324e98c0fd11640da\", \"services_unittests\": \"b7370aab28870e3f970c7ba450e53a5efd300990\", \"shell_dialogs_unittests\": \"968501ed39da6a95ee36b2c032d27a4f0cee204d\", \"skia_unittests\": \"16dfa323524a783ee6d67b6bb824e1cd5e2337c9\", \"snapshot_unittests\": \"2b3a98e9678fa2134d36f1c16d80436436e64dd6\", \"sql_unittests\": \"8dcd0679665edbd2df178372b0cb764bfc7b6ed0\", \"storage_unittests\": \"352f83ebe56f48965167c8cbb20761e677628674\", \"sync_integration_tests\": \"7e263cccff5befa013b0f3d1405bc88780d7cb83\", \"traffic_annotation_auditor_unittests\": \"4a918482781668a7aa782e241b9d04bb9ac8658b\", \"ui_base_unittests\": \"bed29dda796e7483fad2a8447ce18880f6f1e3e9\", \"ui_chromeos_unittests\": \"c613aec201bf78453a0ccee4074a30a3083945e2\", \"ui_touch_selection_unittests\": \"303d9cd8e2b121438c4ceb8133ac4a807a8f79c5\", \"unit_tests\": \"ee6100696ff216ec47902c21489bd3cd882b4a99\", \"url_unittests\": \"5204538271f44926c4413caf9fc70b5e8a363e65\", \"usage_time_limit_unittests\": \"571aaaafee5ce095a92207d1888b092ada91200c\", \"views_unittests\": \"72e3a07bdbf0d96bf3781b801dbd2ecfe3b62b96\", \"viz_unittests\": \"f3606df42ad22a3f3b6001c36934928af904a906\", \"wayland_client_perftests\": \"100096996cf902efaea8ef902d2afad594f9c037\", \"wm_unittests\": \"64f4071c85d6d4e739709f1d5e3183387d52a8f9\", \"wtf_unittests\": \"d4ea2a6fef260eb835a188374f636a0fa5d2866b\", \"zlib_unittests\": \"012c3225ce92d10544c0d497e95c4b5ee6655e43\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-129-zr64\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606086244429152",
"status": "COMPLETED",
"status_changed_ts": "1606091447966526",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43061",
"builder:linux-chromeos-rel",
"buildset:commit/git/dd88b6a26582f86732979863f07e852a22940be2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd88b6a26582f86732979863f07e852a22940be2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028322196028490816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862892133956929328/+/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:500ae8ff811b6710",
"user_agent:luci-scheduler"
],
"updated_ts": "1606091447972895",
"url": "https://ci.chromium.org/b/8862892133956929328",
"utcnow_ts": "1606091448770206"
}
}
[00:30:48.783] Invocation finished in 1h57m5.168495926s with status SUCCEEDED