[18:46:14.452] New invocation is queued and will start shortly
[18:46:15.656] Starting the invocation (attempt 1)
[18:46:15.777] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:46:15.777] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:46:15.777] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021904123635101536",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5c3a1cf23bb05d94e23cc1f440477fa522a14857\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021904123635101536",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"gitiles_ref:refs/heads/master"
]
}
[18:46:16.461] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612205175937037",
"id": "8856474061394500496",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c3a1cf23bb05d94e23cc1f440477fa522a14857\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612205176314432",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22433",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021904123635101536",
"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/8856474061394500496/+/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": "1612205176314660",
"url": "https://ci.chromium.org/b/8856474061394500496",
"utcnow_ts": "1612205176422457"
}
}
[18:46:16.461] Task URL: https://ci.chromium.org/b/8856474061394500496
[18:46:16.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:2:0) after 1m0s
[18:46:20.064] Received PubSub notification, asking Buildbucket for the build status
[18:46:20.196] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:16.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:2:0)
[18:47:16.545] Timer tick, asking Buildbucket for the build status
[18:47:16.645] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:16.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:4:0) after 1m0s
[18:48:16.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:4:0)
[18:48:16.766] Timer tick, asking Buildbucket for the build status
[18:48:17.143] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:17.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:5:0) after 1m0s
[18:49:17.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:5:0)
[18:49:17.275] Timer tick, asking Buildbucket for the build status
[18:49:17.367] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:17.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:6:0) after 1m0s
[18:50:17.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:6:0)
[18:50:17.460] Timer tick, asking Buildbucket for the build status
[18:50:17.905] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:17.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:7:0) after 1m0s
[18:51:17.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:7:0)
[18:51:17.965] Timer tick, asking Buildbucket for the build status
[18:51:18.128] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:18.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:8:0) after 1m0s
[18:52:18.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:8:0)
[18:52:18.196] Timer tick, asking Buildbucket for the build status
[18:52:19.004] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:19.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:9:0) after 1m0s
[18:53:19.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:9:0)
[18:53:19.062] Timer tick, asking Buildbucket for the build status
[18:53:19.455] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:19.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:10:0) after 1m0s
[18:54:19.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:10:0)
[18:54:19.573] Timer tick, asking Buildbucket for the build status
[18:54:19.731] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:19.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:11:0) after 1m0s
[18:55:19.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:11:0)
[18:55:19.850] Timer tick, asking Buildbucket for the build status
[18:55:20.174] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:20.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:12:0) after 1m0s
[18:56:20.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:12:0)
[18:56:20.367] Timer tick, asking Buildbucket for the build status
[18:56:20.605] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:20.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:13:0) after 1m0s
[18:57:20.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:13:0)
[18:57:20.704] Timer tick, asking Buildbucket for the build status
[18:57:20.767] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:20.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:14:0) after 1m0s
[18:58:20.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:14:0)
[18:58:20.847] Timer tick, asking Buildbucket for the build status
[18:58:21.107] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:21.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:15:0) after 1m0s
[18:59:21.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:15:0)
[18:59:21.195] Timer tick, asking Buildbucket for the build status
[18:59:21.300] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:21.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:16:0) after 1m0s
[19:00:21.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:16:0)
[19:00:21.361] Timer tick, asking Buildbucket for the build status
[19:00:21.790] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:21.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:17:0) after 1m0s
[19:01:21.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:17:0)
[19:01:21.836] Timer tick, asking Buildbucket for the build status
[19:01:21.968] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:18:0) after 1m0s
[19:02:22.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:18:0)
[19:02:22.186] Timer tick, asking Buildbucket for the build status
[19:02:23.584] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:23.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:19:0) after 1m0s
[19:03:23.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:19:0)
[19:03:23.692] Timer tick, asking Buildbucket for the build status
[19:03:23.783] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:23.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:20:0) after 1m0s
[19:04:23.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:20:0)
[19:04:23.957] Timer tick, asking Buildbucket for the build status
[19:04:24.024] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:24.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:21:0) after 1m0s
[19:05:24.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:21:0)
[19:05:24.167] Timer tick, asking Buildbucket for the build status
[19:05:24.243] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:24.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:22:0) after 1m0s
[19:06:24.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:22:0)
[19:06:24.368] Timer tick, asking Buildbucket for the build status
[19:06:24.859] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:24.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:23:0) after 1m0s
[19:07:25.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:23:0)
[19:07:25.005] Timer tick, asking Buildbucket for the build status
[19:07:25.132] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:24:0) after 1m0s
[19:08:25.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:24:0)
[19:08:25.173] Timer tick, asking Buildbucket for the build status
[19:08:25.298] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:25.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:25:0) after 1m0s
[19:09:25.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:25:0)
[19:09:25.402] Timer tick, asking Buildbucket for the build status
[19:09:25.527] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:25.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:26:0) after 1m0s
[19:10:25.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:26:0)
[19:10:25.799] Timer tick, asking Buildbucket for the build status
[19:10:26.213] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:27:0) after 1m0s
[19:11:26.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:27:0)
[19:11:26.235] Timer tick, asking Buildbucket for the build status
[19:11:26.368] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:26.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:28:0) after 1m0s
[19:12:26.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:28:0)
[19:12:26.544] Timer tick, asking Buildbucket for the build status
[19:12:26.736] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:26.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:29:0) after 1m0s
[19:13:26.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:29:0)
[19:13:26.904] Timer tick, asking Buildbucket for the build status
[19:13:26.985] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:26.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:30:0) after 1m0s
[19:14:27.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:30:0)
[19:14:27.162] Timer tick, asking Buildbucket for the build status
[19:14:27.267] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:27.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:31:0) after 1m0s
[19:15:27.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:31:0)
[19:15:27.343] Timer tick, asking Buildbucket for the build status
[19:15:27.637] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:27.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:32:0) after 1m0s
[19:16:27.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:32:0)
[19:16:27.688] Timer tick, asking Buildbucket for the build status
[19:16:27.913] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:27.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:33:0) after 1m0s
[19:17:28.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:33:0)
[19:17:28.113] Timer tick, asking Buildbucket for the build status
[19:17:28.272] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:34:0) after 1m0s
[19:18:28.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:34:0)
[19:18:28.493] Timer tick, asking Buildbucket for the build status
[19:18:28.553] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:28.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:35:0) after 1m0s
[19:19:28.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:35:0)
[19:19:28.669] Timer tick, asking Buildbucket for the build status
[19:19:28.753] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:28.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:36:0) after 1m0s
[19:20:28.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:36:0)
[19:20:28.847] Timer tick, asking Buildbucket for the build status
[19:20:29.067] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:29.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:37:0) after 1m0s
[19:21:29.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:37:0)
[19:21:29.147] Timer tick, asking Buildbucket for the build status
[19:21:29.561] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:29.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:38:0) after 1m0s
[19:22:29.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:38:0)
[19:22:29.712] Timer tick, asking Buildbucket for the build status
[19:22:29.794] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:29.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:39:0) after 1m0s
[19:23:29.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:39:0)
[19:23:29.918] Timer tick, asking Buildbucket for the build status
[19:23:30.113] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:30.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:40:0) after 1m0s
[19:24:30.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:40:0)
[19:24:30.165] Timer tick, asking Buildbucket for the build status
[19:24:30.533] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:30.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:41:0) after 1m0s
[19:25:30.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:41:0)
[19:25:30.534] Timer tick, asking Buildbucket for the build status
[19:25:30.645] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:30.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:42:0) after 1m0s
[19:26:30.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:42:0)
[19:26:30.714] Timer tick, asking Buildbucket for the build status
[19:26:30.902] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:30.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:43:0) after 1m0s
[19:27:30.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:43:0)
[19:27:30.928] Timer tick, asking Buildbucket for the build status
[19:27:30.995] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:30.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:44:0) after 1m0s
[19:28:31.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:44:0)
[19:28:31.067] Timer tick, asking Buildbucket for the build status
[19:28:31.217] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:31.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:45:0) after 1m0s
[19:29:31.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:45:0)
[19:29:31.332] Timer tick, asking Buildbucket for the build status
[19:29:31.472] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:31.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:46:0) after 1m0s
[19:30:31.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:46:0)
[19:30:31.570] Timer tick, asking Buildbucket for the build status
[19:30:32.045] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:32.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:47:0) after 1m0s
[19:31:32.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:47:0)
[19:31:32.322] Timer tick, asking Buildbucket for the build status
[19:31:32.432] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:32.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:48:0) after 1m0s
[19:32:32.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:48:0)
[19:32:32.501] Timer tick, asking Buildbucket for the build status
[19:32:32.728] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:32.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:49:0) after 1m0s
[19:33:32.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:49:0)
[19:33:32.798] Timer tick, asking Buildbucket for the build status
[19:33:32.864] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:32.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:50:0) after 1m0s
[19:34:32.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:50:0)
[19:34:32.890] Timer tick, asking Buildbucket for the build status
[19:34:32.951] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:32.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:51:0) after 1m0s
[19:35:33.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:51:0)
[19:35:33.054] Timer tick, asking Buildbucket for the build status
[19:35:33.115] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:33.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:52:0) after 1m0s
[19:36:33.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:52:0)
[19:36:33.144] Timer tick, asking Buildbucket for the build status
[19:36:33.262] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:33.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:53:0) after 1m0s
[19:37:33.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:53:0)
[19:37:33.487] Timer tick, asking Buildbucket for the build status
[19:37:33.591] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:33.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:54:0) after 1m0s
[19:38:34.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:54:0)
[19:38:34.029] Timer tick, asking Buildbucket for the build status
[19:38:34.444] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:34.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:55:0) after 1m0s
[19:39:34.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:55:0)
[19:39:34.747] Timer tick, asking Buildbucket for the build status
[19:39:34.887] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:34.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:56:0) after 1m0s
[19:40:35.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:56:0)
[19:40:35.029] Timer tick, asking Buildbucket for the build status
[19:40:35.177] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:35.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:57:0) after 1m0s
[19:41:35.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:57:0)
[19:41:35.413] Timer tick, asking Buildbucket for the build status
[19:41:35.584] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:35.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:58:0) after 1m0s
[19:42:35.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:58:0)
[19:42:35.772] Timer tick, asking Buildbucket for the build status
[19:42:36.135] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:36.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:59:0) after 1m0s
[19:43:36.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:59:0)
[19:43:36.323] Timer tick, asking Buildbucket for the build status
[19:43:36.456] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:60:0) after 1m0s
[19:44:36.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:60:0)
[19:44:36.519] Timer tick, asking Buildbucket for the build status
[19:44:36.668] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:36.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:61:0) after 1m0s
[19:45:36.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:61:0)
[19:45:36.794] Timer tick, asking Buildbucket for the build status
[19:45:38.949] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:38.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:62:0) after 1m0s
[19:46:39.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:62:0)
[19:46:39.108] Timer tick, asking Buildbucket for the build status
[19:46:39.233] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:39.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:63:0) after 1m0s
[19:47:39.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:63:0)
[19:47:39.645] Timer tick, asking Buildbucket for the build status
[19:47:39.930] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:39.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:64:0) after 1m0s
[19:48:40.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:64:0)
[19:48:40.014] Timer tick, asking Buildbucket for the build status
[19:48:40.114] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:40.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:65:0) after 1m0s
[19:49:40.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:65:0)
[19:49:40.309] Timer tick, asking Buildbucket for the build status
[19:49:40.386] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:40.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:66:0) after 1m0s
[19:50:40.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:66:0)
[19:50:40.525] Timer tick, asking Buildbucket for the build status
[19:50:40.990] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:40.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:67:0) after 1m0s
[19:51:41.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:67:0)
[19:51:41.367] Timer tick, asking Buildbucket for the build status
[19:51:41.569] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:41.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:68:0) after 1m0s
[19:52:41.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:68:0)
[19:52:41.746] Timer tick, asking Buildbucket for the build status
[19:52:42.011] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:42.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:69:0) after 1m0s
[19:53:42.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:69:0)
[19:53:42.209] Timer tick, asking Buildbucket for the build status
[19:53:42.678] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:42.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:70:0) after 1m0s
[19:54:42.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:70:0)
[19:54:42.819] Timer tick, asking Buildbucket for the build status
[19:54:42.890] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:42.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:71:0) after 1m0s
[19:55:44.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:71:0)
[19:55:44.297] Timer tick, asking Buildbucket for the build status
[19:55:44.402] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:44.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:72:0) after 1m0s
[19:56:44.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:72:0)
[19:56:44.458] Timer tick, asking Buildbucket for the build status
[19:56:44.578] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:44.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:73:0) after 1m0s
[19:57:44.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:73:0)
[19:57:44.615] Timer tick, asking Buildbucket for the build status
[19:57:44.789] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:44.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:74:0) after 1m0s
[19:58:44.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:74:0)
[19:58:44.802] Timer tick, asking Buildbucket for the build status
[19:58:45.073] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:45.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:75:0) after 1m0s
[19:59:45.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:75:0)
[19:59:45.144] Timer tick, asking Buildbucket for the build status
[19:59:45.258] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:45.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:76:0) after 1m0s
[20:00:45.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:76:0)
[20:00:45.280] Timer tick, asking Buildbucket for the build status
[20:00:45.377] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:45.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:77:0) after 1m0s
[20:01:45.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:77:0)
[20:01:45.596] Timer tick, asking Buildbucket for the build status
[20:01:45.782] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:45.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:78:0) after 1m0s
[20:02:45.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:78:0)
[20:02:45.864] Timer tick, asking Buildbucket for the build status
[20:02:45.986] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:45.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:79:0) after 1m0s
[20:03:46.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:79:0)
[20:03:46.049] Timer tick, asking Buildbucket for the build status
[20:03:46.136] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:46.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:80:0) after 1m0s
[20:04:46.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:80:0)
[20:04:46.197] Timer tick, asking Buildbucket for the build status
[20:04:46.636] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:46.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:81:0) after 1m0s
[20:05:46.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:81:0)
[20:05:46.659] Timer tick, asking Buildbucket for the build status
[20:05:46.744] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:46.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:82:0) after 1m0s
[20:06:46.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:82:0)
[20:06:46.823] Timer tick, asking Buildbucket for the build status
[20:06:47.419] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:47.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:83:0) after 1m0s
[20:07:47.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:83:0)
[20:07:47.446] Timer tick, asking Buildbucket for the build status
[20:07:47.529] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:47.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:84:0) after 1m0s
[20:08:47.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:84:0)
[20:08:47.641] Timer tick, asking Buildbucket for the build status
[20:08:48.206] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:48.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:85:0) after 1m0s
[20:09:48.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:85:0)
[20:09:48.305] Timer tick, asking Buildbucket for the build status
[20:09:48.612] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:48.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:86:0) after 1m0s
[20:10:48.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:86:0)
[20:10:48.756] Timer tick, asking Buildbucket for the build status
[20:10:49.080] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:49.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:87:0) after 1m0s
[20:11:49.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:87:0)
[20:11:49.068] Timer tick, asking Buildbucket for the build status
[20:11:49.250] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:49.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:88:0) after 1m0s
[20:12:49.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:88:0)
[20:12:49.298] Timer tick, asking Buildbucket for the build status
[20:12:49.378] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:49.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:89:0) after 1m0s
[20:13:49.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:89:0)
[20:13:49.468] Timer tick, asking Buildbucket for the build status
[20:13:49.825] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:49.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:90:0) after 1m0s
[20:14:49.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:90:0)
[20:14:49.929] Timer tick, asking Buildbucket for the build status
[20:14:50.401] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:50.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:91:0) after 1m0s
[20:15:50.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:91:0)
[20:15:50.467] Timer tick, asking Buildbucket for the build status
[20:15:50.608] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:50.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:92:0) after 1m0s
[20:16:50.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:92:0)
[20:16:50.729] Timer tick, asking Buildbucket for the build status
[20:16:50.965] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:50.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:93:0) after 1m0s
[20:17:51.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:93:0)
[20:17:51.031] Timer tick, asking Buildbucket for the build status
[20:17:51.213] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:51.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:94:0) after 1m0s
[20:18:51.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:94:0)
[20:18:51.302] Timer tick, asking Buildbucket for the build status
[20:18:51.428] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:51.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:95:0) after 1m0s
[20:19:51.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:95:0)
[20:19:51.498] Timer tick, asking Buildbucket for the build status
[20:19:51.630] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:51.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:96:0) after 1m0s
[20:20:51.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:96:0)
[20:20:51.717] Timer tick, asking Buildbucket for the build status
[20:20:51.783] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:51.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:97:0) after 1m0s
[20:21:51.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:97:0)
[20:21:51.787] Timer tick, asking Buildbucket for the build status
[20:21:51.968] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:51.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:98:0) after 1m0s
[20:22:52.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:98:0)
[20:22:52.054] Timer tick, asking Buildbucket for the build status
[20:22:52.213] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:52.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:99:0) after 1m0s
[20:23:52.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:99:0)
[20:23:52.277] Timer tick, asking Buildbucket for the build status
[20:23:52.363] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:52.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:100:0) after 1m0s
[20:24:52.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:100:0)
[20:24:52.700] Timer tick, asking Buildbucket for the build status
[20:24:52.797] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:52.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:101:0) after 1m0s
[20:25:52.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:101:0)
[20:25:52.923] Timer tick, asking Buildbucket for the build status
[20:25:53.001] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:53.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:102:0) after 1m0s
[20:26:53.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:102:0)
[20:26:53.139] Timer tick, asking Buildbucket for the build status
[20:26:53.327] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:53.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:103:0) after 1m0s
[20:27:53.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:103:0)
[20:27:53.447] Timer tick, asking Buildbucket for the build status
[20:27:53.527] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:53.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:104:0) after 1m0s
[20:28:54.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:104:0)
[20:28:54.111] Timer tick, asking Buildbucket for the build status
[20:28:54.289] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:54.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:105:0) after 1m0s
[20:29:54.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:105:0)
[20:29:54.436] Timer tick, asking Buildbucket for the build status
[20:29:54.508] Build 8856474061394500496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:54.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021904123635101536:106:0) after 1m0s
[20:30:18.483] Received PubSub notification, asking Buildbucket for the build status
[20:30:18.567] Build 8856474061394500496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:30:18.567] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612211417605293",
"created_by": "project:chromium",
"created_ts": "1612205175937037",
"id": "8856474061394500496",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c3a1cf23bb05d94e23cc1f440477fa522a14857\"}}",
"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\": \"5c3a1cf23bb05d94e23cc1f440477fa522a14857\", \"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-93-pui4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22433, \"got_angle_revision\": \"e3096d0729df059f1d251405d16eef9e71ba2516\", \"got_dawn_revision\": \"d7aacdda1119d419ce886f9ba4b9401de2834bed\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"5c3a1cf23bb05d94e23cc1f440477fa522a14857\", \"got_revision_cp\": \"refs/heads/master@{#849181}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a483f3814f0dd7d5e1a4ec18cc555b6a37d2f9dd\", \"got_v8_revision\": \"e60468ddb6a9b78c3eef0e40c1f17c73c8b0d2c0\", \"got_v8_revision_cp\": \"refs/heads/9.0.104@{#1}\", \"got_webrtc_revision\": \"989e6e7d22e281dde977c349355a8f1d76a8dff3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33120}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c3a1cf23bb05d94e23cc1f440477fa522a14857\", \"swarm_hashes_refs/heads/master(at){#849181}_without_patch\": {\"absl_hardening_tests\": \"49ea8d83a8ea7982afc421ffbba81ed7e0281e50\", \"accessibility_unittests\": \"6c91247b7c5b4dd19756e7fd262bc8046b2b9810\", \"angle_unittests\": \"68eef97c97da6981aa9410ed4073f45967543ebd\", \"app_list_unittests\": \"3a446691d78868c50bb98bdefc5331044001db59\", \"app_shell_unittests\": \"fa5025517a8cf9d9e60a735461753bb78e0c8c0f\", \"ash_unittests\": \"9c4594c802ef1464d4fbf624a5804bc50b19bb07\", \"aura_unittests\": \"1f79aaeb621ae97304ae7a5cbb054019b73e3218\", \"base_unittests\": \"a21faf6542c3dca3e2b18a90210cdeaf8b8cc895\", \"base_util_unittests\": \"a941ec51a0128ca9cd7704880e99ccceb2827ccd\", \"blink_common_unittests\": \"84ea3d0c10e818667600d772fb0541544b80a356\", \"blink_fuzzer_unittests\": \"d8a5e76e4b8bd748eb00664cdf5aed217f5eb8d1\", \"blink_heap_unittests\": \"d0991136f77a1ed52aa66473a45d8208092459df\", \"blink_platform_unittests\": \"a4450784d60c700c6c36f2cf1c63e970b96f2084\", \"blink_unittests\": \"ea63a2c166940c52be7ee7672439c3d184a626f8\", \"boringssl_crypto_tests\": \"b7d6cc1c2056d75ae0cae63f6365124d2be61809\", \"boringssl_ssl_tests\": \"b50a828c760ba7739138300783bde4bf8dd3ff31\", \"browser_tests\": \"4186b69c9a3cce1eae6876abbccd50398bd58500\", \"capture_unittests\": \"9f2ed18a79955b864d264a7a51d63b9fa93a6294\", \"cast_unittests\": \"3fb4411cc064674c3885f081b2f530f18fbe9fdb\", \"cc_unittests\": \"577f844a2c5c9c17a330dcd0c0d1084b09f1a640\", \"chrome_app_unittests\": \"23512c080b49bca60317bcaa1ea8114c62eecdde\", \"chromedriver_unittests\": \"35d34d71ea545bdcb6f723d1d868f5bd8e67383f\", \"chromeos_components_unittests\": \"1bfbb55ffa8b167915a327e875d4f7187b883950\", \"chromeos_unittests\": \"b0779860a8f2aaa66af7c688e1ad713f8aa52225\", \"components_browsertests\": \"4b36014b8f0ef1b06bb28a458501986790e66c1c\", \"components_unittests\": \"4d59956f9e3e723786b22b05e523b11e2277ea56\", \"compositor_unittests\": \"b8780413633f8cfc7baa512b6b6f121fb8de53c4\", \"content_browsertests\": \"31606a05ee8df56c347f47796c81e2381a1aaaaf\", \"content_unittests\": \"e912eec744753698f3921901ab2cbafb54e61c65\", \"crashpad_tests\": \"95a938dd0b90517c5e698343f42257babd553d55\", \"crypto_unittests\": \"ec58b17768ad80649056ac383ee404c4c62a0493\", \"dbus_unittests\": \"530b0f405fa7c0f16f1caf197d22fdb4c69a897c\", \"device_unittests\": \"23a8b912a475b128de9fb7d98053441e43131dd6\", \"display_unittests\": \"81199037597cfb9e4f349c47cdc7498592560c85\", \"events_unittests\": \"1ae72667d5c308cfa614fddc33c5debf6e6f82d9\", \"exo_unittests\": \"324c0daf0f094c99469c9981f095ee068d124b9e\", \"extensions_browsertests\": \"fcad9553fc037312a8a820aaed5281c4d40fb1d8\", \"extensions_unittests\": \"e5ea722e296ef7b385d65fcf51b838c4b672c6c8\", \"filesystem_service_unittests\": \"494051193865c270d2c2bb37df546faa6e3abe97\", \"gcm_unit_tests\": \"795f9b1bfe6c322ece469f60996d4a259e7a128c\", \"gfx_unittests\": \"d6668307fabf277b332397c6b997e69d37633362\", \"gin_unittests\": \"b5b8e1845408ed93712f6c570cf917dca1a20671\", \"gl_unittests_ozone\": \"88901c0ff14cacf68fba755229a51db56f74e500\", \"google_apis_unittests\": \"19542ea7f3bfc17c9e9406cd4fc8592911131384\", \"gpu_unittests\": \"ac91ebc5d89c6c3d24047218998b7491ec2624f5\", \"gwp_asan_unittests\": \"9eb0467b959e7927f142023e0ee6e67ad792a6d0\", \"interactive_ui_tests\": \"9cdae582aa386eb5591d482386e8a0bece4b8b54\", \"ipc_tests\": \"c3d7f87015fccbc243b34ec22760f309c741dcfe\", \"jingle_unittests\": \"69076089ac74cce7e1663c79129844a18ab71572\", \"keyboard_unittests\": \"65da588e974cb07b8795f1eeb1b08b863fba5a93\", \"latency_unittests\": \"15254407202e9a3ea872736c63399dfc755cb313\", \"libjingle_xmpp_unittests\": \"fa2502f28e9c618592f98a1022783c911aa5ac91\", \"liburlpattern_unittests\": \"e974a62401b4d112812962d32f34bb11a85ac32b\", \"media_blink_unittests\": \"16e3de9a9b79cd38ce64b3f4b142a3372602fb2a\", \"media_unittests\": \"784ddc950117e92be15548fc3dfb4e1ec085beb5\", \"message_center_unittests\": \"48a656bf1ff860650ebd3dedc01df05c17f7b19e\", \"midi_unittests\": \"bd68b94296a584781e983fc52c1b082b06983a87\", \"mojo_core_unittests\": \"16a3e87af5e68c36c062ea0ddf9279db70a5715f\", \"mojo_unittests\": \"ab2183656c6ce6aed0fda7d7ea52fd78f5b5d9db\", \"nacl_helper_nonsfi_unittests\": \"f8cac38238b580106fd593f6ef27e3f32570cbb9\", \"nacl_loader_unittests\": \"3d2042b1fce3f8cb4dbc6984b0e3fd8b381e4ba0\", \"native_theme_unittests\": \"42f0b38b4b9e04c112cdbc37fcb74cd466f84fc7\", \"net_unittests\": \"23f58b238dadda26257662c524d2016b263417ff\", \"ozone_gl_unittests\": \"0d9f5d571909276a80ab3abe70773d7bfe15ebdc\", \"ozone_unittests\": \"9d3667c206e8053dfcc12b15f11ddcf0c11ac4cd\", \"ozone_x11_unittests\": \"3c4b14881fa32d55365553f83f43a59378788f6b\", \"pdf_unittests\": \"cd8261e02ceea296087c820af1327924b0d08c20\", \"perfetto_unittests\": \"9b5f1b6f4946df8d978f865539e1fed0162761b1\", \"ppapi_unittests\": \"28952087fda5ac850120e8f3c32bcbe09aefad5e\", \"printing_unittests\": \"84cc54f629c8a7f0b4635a5578cf38c559062900\", \"remoting_unittests\": \"6cfddbd632f11c4d972dc386b46a4e5e31487c9a\", \"sandbox_linux_unittests\": \"e13544506dc449649472eab3cfca82ffb0390e72\", \"service_manager_unittests\": \"edc5375fd0e148ffacd9b3283fc6a7b4d52c97d4\", \"services_unittests\": \"7463f8b321bc4a29f121db274c4018038d5179a7\", \"shell_dialogs_unittests\": \"4d7e57e48abbcb13e20875a64a53244d123a4951\", \"shell_encryption_unittests\": \"d51e077cd3df00e477c5087a7fd07044a868e54d\", \"skia_unittests\": \"93f04ff9174eee1377439c288002acd056c03d97\", \"snapshot_unittests\": \"18fbe9f6a86ea6e7a74633eb0ecad4bef3f54c9d\", \"sql_unittests\": \"70c1a1c759b309ef692f852ad804cf44f7917a1f\", \"storage_unittests\": \"43484c9bdd6a7be03a6361edb8634b5b6f94e737\", \"sync_integration_tests\": \"000008d52432f008ae92ca1eeb8f8f1e9b9f994f\", \"traffic_annotation_auditor_unittests\": \"6a71d5df8eb48dbacbacd7d612ce07bc6366dc20\", \"ui_base_unittests\": \"f0cb8fbff995779d9117544e6fd55c3bbc958ca2\", \"ui_chromeos_unittests\": \"a15f4d7fb501c6cacd06a057d1afbace1a7a0dfc\", \"ui_touch_selection_unittests\": \"714c4dcd5dd1fed859c374d9bdf2e4f95b66df4e\", \"unit_tests\": \"5a3a25594ad0d76bcf2fa4cfe4fb2fda876e91e3\", \"url_unittests\": \"569fd9eab84152a3e3e2bb2970c61a678e5b4d12\", \"usage_time_limit_unittests\": \"3d1c2612f83edcb5b078a08f4e82fe956645247e\", \"views_unittests\": \"68b7eacbc362097c0ca2f0770b4fe1a64a21f330\", \"viz_unittests\": \"239e8e92dea6d8eef47c92add85c722e79668448\", \"wm_unittests\": \"a62865926aa7c167ad73069c320f3186fe7bd0b5\", \"wtf_unittests\": \"e3a786f6f1638676164e5f270d8525816b559a9b\", \"zlib_unittests\": \"08952f54ab2cbe82ec16252356473b38ba92bbc0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-93-pui4\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612205179288822",
"status": "COMPLETED",
"status_changed_ts": "1612211417969546",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22433",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c3a1cf23bb05d94e23cc1f440477fa522a14857",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021904123635101536",
"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/8856474061394500496/+/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:5177bc41b1ef1810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612211417973994",
"url": "https://ci.chromium.org/b/8856474061394500496",
"utcnow_ts": "1612211418557573"
}
}
[20:30:18.567] Invocation finished in 1h44m4.150052922s with status SUCCEEDED