[14:00:12.300] New invocation is queued and will start shortly
[14:00:13.439] Starting the invocation (attempt 1)
[14:00:13.487] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:00:13.488] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:00:13.488] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023099880006491344",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c66d101f484f66455fe208f6196b23846437a318\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023099880006491344",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/c66d101f484f66455fe208f6196b23846437a318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c66d101f484f66455fe208f6196b23846437a318",
"gitiles_ref:refs/heads/master"
]
}
[14:00:14.706] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611064813599370",
"id": "8857669817973996288",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c66d101f484f66455fe208f6196b23846437a318\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611064814419016",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22257",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c66d101f484f66455fe208f6196b23846437a318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c66d101f484f66455fe208f6196b23846437a318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023099880006491344",
"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/8857669817973996288/+/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": "1611064814419126",
"url": "https://ci.chromium.org/b/8857669817973996288",
"utcnow_ts": "1611064814695504"
}
}
[14:00:14.706] Task URL: https://ci.chromium.org/b/8857669817973996288
[14:00:14.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:2:0) after 1m0s
[14:00:19.080] Received PubSub notification, asking Buildbucket for the build status
[14:00:19.173] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:14.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:2:0)
[14:01:14.806] Timer tick, asking Buildbucket for the build status
[14:01:14.904] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:14.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:4:0) after 1m0s
[14:02:15.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:4:0)
[14:02:15.053] Timer tick, asking Buildbucket for the build status
[14:02:15.115] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:15.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:5:0) after 1m0s
[14:03:15.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:5:0)
[14:03:15.256] Timer tick, asking Buildbucket for the build status
[14:03:15.321] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:15.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:6:0) after 1m0s
[14:04:15.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:6:0)
[14:04:15.484] Timer tick, asking Buildbucket for the build status
[14:04:15.776] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:15.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:7:0) after 1m0s
[14:05:15.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:7:0)
[14:05:15.931] Timer tick, asking Buildbucket for the build status
[14:05:16.206] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:16.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:8:0) after 1m0s
[14:06:16.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:8:0)
[14:06:16.326] Timer tick, asking Buildbucket for the build status
[14:06:16.553] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:16.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:9:0) after 1m0s
[14:07:16.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:9:0)
[14:07:16.587] Timer tick, asking Buildbucket for the build status
[14:07:16.948] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:16.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:10:0) after 1m0s
[14:08:17.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:10:0)
[14:08:17.035] Timer tick, asking Buildbucket for the build status
[14:08:17.092] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:17.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:11:0) after 1m0s
[14:09:17.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:11:0)
[14:09:17.129] Timer tick, asking Buildbucket for the build status
[14:09:17.199] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:17.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:12:0) after 1m0s
[14:10:17.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:12:0)
[14:10:17.241] Timer tick, asking Buildbucket for the build status
[14:10:17.545] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:17.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:13:0) after 1m0s
[14:11:17.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:13:0)
[14:11:17.713] Timer tick, asking Buildbucket for the build status
[14:11:18.003] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:18.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:14:0) after 1m0s
[14:12:18.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:14:0)
[14:12:18.005] Timer tick, asking Buildbucket for the build status
[14:12:18.062] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:18.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:15:0) after 1m0s
[14:13:18.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:15:0)
[14:13:18.116] Timer tick, asking Buildbucket for the build status
[14:13:18.169] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:18.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:16:0) after 1m0s
[14:14:18.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:16:0)
[14:14:18.265] Timer tick, asking Buildbucket for the build status
[14:14:18.573] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:18.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:17:0) after 1m0s
[14:15:18.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:17:0)
[14:15:18.624] Timer tick, asking Buildbucket for the build status
[14:15:19.308] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:19.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:18:0) after 1m0s
[14:16:19.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:18:0)
[14:16:19.456] Timer tick, asking Buildbucket for the build status
[14:16:19.511] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:19.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:19:0) after 1m0s
[14:17:19.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:19:0)
[14:17:19.640] Timer tick, asking Buildbucket for the build status
[14:17:19.705] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:19.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:20:0) after 1m0s
[14:18:19.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:20:0)
[14:18:19.845] Timer tick, asking Buildbucket for the build status
[14:18:19.914] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:19.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:21:0) after 1m0s
[14:19:20.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:21:0)
[14:19:20.064] Timer tick, asking Buildbucket for the build status
[14:19:20.150] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:20.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:22:0) after 1m0s
[14:20:20.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:22:0)
[14:20:20.222] Timer tick, asking Buildbucket for the build status
[14:20:20.629] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:23:0) after 1m0s
[14:21:20.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:23:0)
[14:21:20.703] Timer tick, asking Buildbucket for the build status
[14:21:20.779] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:20.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:24:0) after 1m0s
[14:22:20.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:24:0)
[14:22:20.843] Timer tick, asking Buildbucket for the build status
[14:22:20.999] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:20.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:25:0) after 1m0s
[14:23:20.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:25:0)
[14:23:20.984] Timer tick, asking Buildbucket for the build status
[14:23:21.146] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:21.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:26:0) after 1m0s
[14:24:21.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:26:0)
[14:24:21.208] Timer tick, asking Buildbucket for the build status
[14:24:21.414] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:21.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:27:0) after 1m0s
[14:25:21.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:27:0)
[14:25:21.449] Timer tick, asking Buildbucket for the build status
[14:25:22.009] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:22.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:28:0) after 1m0s
[14:26:22.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:28:0)
[14:26:22.149] Timer tick, asking Buildbucket for the build status
[14:26:22.280] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:22.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:29:0) after 1m0s
[14:27:22.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:29:0)
[14:27:22.326] Timer tick, asking Buildbucket for the build status
[14:27:22.397] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:22.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:30:0) after 1m0s
[14:28:22.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:30:0)
[14:28:22.421] Timer tick, asking Buildbucket for the build status
[14:28:22.496] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:22.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:31:0) after 1m0s
[14:29:22.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:31:0)
[14:29:22.529] Timer tick, asking Buildbucket for the build status
[14:29:22.776] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:22.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:32:0) after 1m0s
[14:30:22.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:32:0)
[14:30:22.811] Timer tick, asking Buildbucket for the build status
[14:30:23.002] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:23.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:33:0) after 1m0s
[14:31:23.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:33:0)
[14:31:23.217] Timer tick, asking Buildbucket for the build status
[14:31:23.276] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:23.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:34:0) after 1m0s
[14:32:23.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:34:0)
[14:32:23.484] Timer tick, asking Buildbucket for the build status
[14:32:23.695] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:23.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:35:0) after 1m0s
[14:33:23.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:35:0)
[14:33:23.718] Timer tick, asking Buildbucket for the build status
[14:33:23.778] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:23.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:36:0) after 1m0s
[14:34:23.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:36:0)
[14:34:23.891] Timer tick, asking Buildbucket for the build status
[14:34:23.985] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:23.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:37:0) after 1m0s
[14:35:24.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:37:0)
[14:35:24.017] Timer tick, asking Buildbucket for the build status
[14:35:24.127] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:24.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:38:0) after 1m0s
[14:36:24.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:38:0)
[14:36:24.233] Timer tick, asking Buildbucket for the build status
[14:36:24.357] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:24.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:39:0) after 1m0s
[14:37:24.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:39:0)
[14:37:24.349] Timer tick, asking Buildbucket for the build status
[14:37:24.406] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:24.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:40:0) after 1m0s
[14:38:24.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:40:0)
[14:38:24.436] Timer tick, asking Buildbucket for the build status
[14:38:24.569] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:24.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:41:0) after 1m0s
[14:39:24.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:41:0)
[14:39:24.645] Timer tick, asking Buildbucket for the build status
[14:39:24.795] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:24.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:42:0) after 1m0s
[14:40:24.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:42:0)
[14:40:24.925] Timer tick, asking Buildbucket for the build status
[14:40:25.122] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:25.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:43:0) after 1m0s
[14:41:25.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:43:0)
[14:41:25.221] Timer tick, asking Buildbucket for the build status
[14:41:25.319] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:25.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:44:0) after 1m0s
[14:42:25.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:44:0)
[14:42:25.467] Timer tick, asking Buildbucket for the build status
[14:42:25.564] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:25.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:45:0) after 1m0s
[14:43:25.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:45:0)
[14:43:25.594] Timer tick, asking Buildbucket for the build status
[14:43:25.693] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:25.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:46:0) after 1m0s
[14:44:25.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:46:0)
[14:44:25.714] Timer tick, asking Buildbucket for the build status
[14:44:25.860] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:25.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:47:0) after 1m0s
[14:45:25.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:47:0)
[14:45:25.920] Timer tick, asking Buildbucket for the build status
[14:45:26.047] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:26.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:48:0) after 1m0s
[14:46:26.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:48:0)
[14:46:26.069] Timer tick, asking Buildbucket for the build status
[14:46:26.159] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:26.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:49:0) after 1m0s
[14:47:26.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:49:0)
[14:47:26.182] Timer tick, asking Buildbucket for the build status
[14:47:26.268] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:26.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:50:0) after 1m0s
[14:48:26.348] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:50:0)
[14:48:26.348] Timer tick, asking Buildbucket for the build status
[14:48:26.454] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:26.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:51:0) after 1m0s
[14:49:26.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:51:0)
[14:49:26.486] Timer tick, asking Buildbucket for the build status
[14:49:26.577] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:26.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:52:0) after 1m0s
[14:50:26.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:52:0)
[14:50:26.570] Timer tick, asking Buildbucket for the build status
[14:50:26.718] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:26.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:53:0) after 1m0s
[14:51:26.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:53:0)
[14:51:26.754] Timer tick, asking Buildbucket for the build status
[14:51:27.539] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:27.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:54:0) after 1m0s
[14:52:27.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:54:0)
[14:52:27.592] Timer tick, asking Buildbucket for the build status
[14:52:27.699] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:27.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:55:0) after 1m0s
[14:53:27.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:55:0)
[14:53:27.721] Timer tick, asking Buildbucket for the build status
[14:53:27.812] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:27.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:56:0) after 1m0s
[14:54:27.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:56:0)
[14:54:27.859] Timer tick, asking Buildbucket for the build status
[14:54:27.979] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:27.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:57:0) after 1m0s
[14:55:28.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:57:0)
[14:55:28.176] Timer tick, asking Buildbucket for the build status
[14:55:28.303] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:28.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:58:0) after 1m0s
[14:56:28.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:58:0)
[14:56:28.371] Timer tick, asking Buildbucket for the build status
[14:56:28.587] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:28.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:59:0) after 1m0s
[14:57:28.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:59:0)
[14:57:28.622] Timer tick, asking Buildbucket for the build status
[14:57:29.190] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:29.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:60:0) after 1m0s
[14:58:29.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:60:0)
[14:58:29.316] Timer tick, asking Buildbucket for the build status
[14:58:29.916] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:29.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:61:0) after 1m0s
[14:59:29.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:61:0)
[14:59:29.961] Timer tick, asking Buildbucket for the build status
[14:59:30.040] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:30.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:62:0) after 1m0s
[15:00:30.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:62:0)
[15:00:30.148] Timer tick, asking Buildbucket for the build status
[15:00:30.360] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:30.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:63:0) after 1m0s
[15:01:30.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:63:0)
[15:01:30.538] Timer tick, asking Buildbucket for the build status
[15:01:30.613] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:30.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:64:0) after 1m0s
[15:02:30.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:64:0)
[15:02:30.825] Timer tick, asking Buildbucket for the build status
[15:02:30.918] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:30.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:65:0) after 1m0s
[15:03:30.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:65:0)
[15:03:30.969] Timer tick, asking Buildbucket for the build status
[15:03:31.101] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:31.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:66:0) after 1m0s
[15:04:31.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:66:0)
[15:04:31.121] Timer tick, asking Buildbucket for the build status
[15:04:31.487] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:31.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:67:0) after 1m0s
[15:05:31.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:67:0)
[15:05:31.532] Timer tick, asking Buildbucket for the build status
[15:05:31.640] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:31.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:68:0) after 1m0s
[15:06:31.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:68:0)
[15:06:31.687] Timer tick, asking Buildbucket for the build status
[15:06:31.807] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:31.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:69:0) after 1m0s
[15:07:31.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:69:0)
[15:07:31.864] Timer tick, asking Buildbucket for the build status
[15:07:31.940] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:31.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:70:0) after 1m0s
[15:08:31.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:70:0)
[15:08:31.989] Timer tick, asking Buildbucket for the build status
[15:08:32.107] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:32.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:71:0) after 1m0s
[15:09:32.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:71:0)
[15:09:32.137] Timer tick, asking Buildbucket for the build status
[15:09:32.357] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:32.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:72:0) after 1m0s
[15:10:32.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:72:0)
[15:10:32.386] Timer tick, asking Buildbucket for the build status
[15:10:32.813] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:32.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:73:0) after 1m0s
[15:11:32.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:73:0)
[15:11:32.865] Timer tick, asking Buildbucket for the build status
[15:11:32.947] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:32.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:74:0) after 1m0s
[15:12:32.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:74:0)
[15:12:32.971] Timer tick, asking Buildbucket for the build status
[15:12:33.044] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:33.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:75:0) after 1m0s
[15:13:33.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:75:0)
[15:13:33.032] Timer tick, asking Buildbucket for the build status
[15:13:33.184] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:33.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:76:0) after 1m0s
[15:14:33.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:76:0)
[15:14:33.205] Timer tick, asking Buildbucket for the build status
[15:14:33.575] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:33.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:77:0) after 1m0s
[15:15:33.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:77:0)
[15:15:33.564] Timer tick, asking Buildbucket for the build status
[15:15:33.784] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:33.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:78:0) after 1m0s
[15:16:33.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:78:0)
[15:16:33.838] Timer tick, asking Buildbucket for the build status
[15:16:33.916] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:33.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:79:0) after 1m0s
[15:17:33.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:79:0)
[15:17:33.937] Timer tick, asking Buildbucket for the build status
[15:17:34.089] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:34.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:80:0) after 1m0s
[15:18:34.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:80:0)
[15:18:34.117] Timer tick, asking Buildbucket for the build status
[15:18:34.441] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:34.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:81:0) after 1m0s
[15:19:34.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:81:0)
[15:19:34.467] Timer tick, asking Buildbucket for the build status
[15:19:34.855] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:34.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:82:0) after 1m0s
[15:20:34.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:82:0)
[15:20:34.864] Timer tick, asking Buildbucket for the build status
[15:20:34.966] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:34.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:83:0) after 1m0s
[15:21:35.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:83:0)
[15:21:35.022] Timer tick, asking Buildbucket for the build status
[15:21:35.093] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:35.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:84:0) after 1m0s
[15:22:35.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:84:0)
[15:22:35.135] Timer tick, asking Buildbucket for the build status
[15:22:35.219] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:35.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:85:0) after 1m0s
[15:23:35.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:85:0)
[15:23:35.333] Timer tick, asking Buildbucket for the build status
[15:23:36.071] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:36.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:86:0) after 1m0s
[15:24:36.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:86:0)
[15:24:36.116] Timer tick, asking Buildbucket for the build status
[15:24:36.239] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:36.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:87:0) after 1m0s
[15:25:36.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:87:0)
[15:25:36.290] Timer tick, asking Buildbucket for the build status
[15:25:36.426] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:36.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:88:0) after 1m0s
[15:26:36.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:88:0)
[15:26:36.484] Timer tick, asking Buildbucket for the build status
[15:26:36.548] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:36.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:89:0) after 1m0s
[15:27:36.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:89:0)
[15:27:36.579] Timer tick, asking Buildbucket for the build status
[15:27:36.647] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:36.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:90:0) after 1m0s
[15:28:36.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:90:0)
[15:28:36.701] Timer tick, asking Buildbucket for the build status
[15:28:36.771] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:36.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:91:0) after 1m0s
[15:29:36.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:91:0)
[15:29:36.862] Timer tick, asking Buildbucket for the build status
[15:29:36.953] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:36.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:92:0) after 1m0s
[15:30:37.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:92:0)
[15:30:37.057] Timer tick, asking Buildbucket for the build status
[15:30:37.177] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:37.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:93:0) after 1m0s
[15:31:37.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:93:0)
[15:31:37.243] Timer tick, asking Buildbucket for the build status
[15:31:37.388] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:37.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:94:0) after 1m0s
[15:32:37.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:94:0)
[15:32:37.537] Timer tick, asking Buildbucket for the build status
[15:32:37.805] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:37.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:95:0) after 1m0s
[15:33:37.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:95:0)
[15:33:37.858] Timer tick, asking Buildbucket for the build status
[15:33:37.938] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:37.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:96:0) after 1m0s
[15:34:37.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:96:0)
[15:34:37.962] Timer tick, asking Buildbucket for the build status
[15:34:38.192] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:38.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:97:0) after 1m0s
[15:35:38.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:97:0)
[15:35:38.263] Timer tick, asking Buildbucket for the build status
[15:35:38.387] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:38.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:98:0) after 1m0s
[15:36:38.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:98:0)
[15:36:38.477] Timer tick, asking Buildbucket for the build status
[15:36:38.688] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:38.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:99:0) after 1m0s
[15:37:38.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:99:0)
[15:37:38.710] Timer tick, asking Buildbucket for the build status
[15:37:38.780] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:38.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:100:0) after 1m0s
[15:38:38.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:100:0)
[15:38:38.875] Timer tick, asking Buildbucket for the build status
[15:38:38.959] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:38.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:101:0) after 1m0s
[15:39:39.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:101:0)
[15:39:39.136] Timer tick, asking Buildbucket for the build status
[15:39:39.217] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:39.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:102:0) after 1m0s
[15:40:39.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:102:0)
[15:40:39.536] Timer tick, asking Buildbucket for the build status
[15:40:40.312] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:40.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:103:0) after 1m0s
[15:41:40.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:103:0)
[15:41:40.496] Timer tick, asking Buildbucket for the build status
[15:41:40.865] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:40.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:104:0) after 1m0s
[15:42:41.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:104:0)
[15:42:41.019] Timer tick, asking Buildbucket for the build status
[15:42:41.185] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:41.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:105:0) after 1m0s
[15:43:41.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:105:0)
[15:43:41.220] Timer tick, asking Buildbucket for the build status
[15:43:41.303] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:41.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:106:0) after 1m0s
[15:44:41.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:106:0)
[15:44:41.507] Timer tick, asking Buildbucket for the build status
[15:44:41.593] Build 8857669817973996288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023099880006491344:107:0) after 1m0s
[15:44:52.650] Received PubSub notification, asking Buildbucket for the build status
[15:44:52.917] Build 8857669817973996288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:44:52.917] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611071091403774",
"created_by": "project:chromium",
"created_ts": "1611064813599370",
"id": "8857669817973996288",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c66d101f484f66455fe208f6196b23846437a318\"}}",
"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\": \"c66d101f484f66455fe208f6196b23846437a318\", \"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-86-wu7l\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22257, \"got_angle_revision\": \"b69f54069cbf700a4646817805ca77c0d2a01292\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"c66d101f484f66455fe208f6196b23846437a318\", \"got_revision_cp\": \"refs/heads/master@{#844755}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"035dfc7b4d7aa680a6e9be05ce752231ae227bea\", \"got_v8_revision_cp\": \"refs/heads/9.0.12@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"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\": \"c66d101f484f66455fe208f6196b23846437a318\", \"swarm_hashes_refs/heads/master(at){#844755}_without_patch\": {\"absl_hardening_tests\": \"f507254870a66da721d776f282bac06cce775a6f\", \"accessibility_unittests\": \"82f85fcdf984a2e3d44934881dafdfc1600a9e66\", \"angle_unittests\": \"28b580e71c329e8b43638066516376341f31bbb3\", \"app_list_unittests\": \"28ad3c62f8ce6f155601fb9409c5016ce8ec9d9f\", \"app_shell_unittests\": \"bd9590b31e0bb1505683dc249c938f7a9373df25\", \"ash_unittests\": \"8f786c06112a925c9197512c7041448ebe2536af\", \"aura_unittests\": \"57cee83ec6cbb13a4be07090dbc62ed4ac97787c\", \"base_unittests\": \"7f64a38d23c7d210d9e21d97d8a91350f98d6853\", \"base_util_unittests\": \"35aef6ceb19f3396a357c8a445cfd070cf8e09f7\", \"blink_common_unittests\": \"bc50787802f66473be212d2a60e0a86a50468df3\", \"blink_fuzzer_unittests\": \"b0a31c86117410552b76e4f0470cf9ea1c2fae25\", \"blink_heap_unittests\": \"bbc69a9047d8cd96d68307619ac5f7491daa7a03\", \"blink_platform_unittests\": \"5137d21f7cf40e72bfa9abdbf02b8b652936c882\", \"blink_unittests\": \"dee9207c548ac6a1ddfb03bec699d405619a6ae5\", \"boringssl_crypto_tests\": \"e63aa6b4c7f5cbc8458ea033a0591f2abd071d66\", \"boringssl_ssl_tests\": \"39cd58b84d023b7afb91f19d5707f841b0752d57\", \"browser_tests\": \"f15ec6799469fcf224ee11ed57dec0027c981512\", \"capture_unittests\": \"a29353c4c63be85517aa59dd719a8004708b76c6\", \"cast_unittests\": \"6bde77ed5ebc92363008d71cde5cf76eccb19466\", \"cc_unittests\": \"e76f92edbed3c9700528fa7ca4ce40f1a74a3b24\", \"chrome_app_unittests\": \"1a98df5b1a4c078e8b4c7ed952fa791de2beaa6e\", \"chromedriver_unittests\": \"7a4b6f96f32733b1c9087dcc0571283359fb80d6\", \"chromeos_components_unittests\": \"0ffee75c41784b485702702446838ce2566b4f80\", \"chromeos_unittests\": \"f52832f1500e8e56a954835fcca5fb48009a46fe\", \"components_browsertests\": \"cbfa8caca069aa25743a3fcc5c8d061e46ee4876\", \"components_unittests\": \"20ca44c444d01ef5319636202b75c902f846374b\", \"compositor_unittests\": \"203815ed47c159bcd4f8e8fdff5ea803dec659c1\", \"content_browsertests\": \"259478ad0181044de88d79f0de1f89655a155964\", \"content_unittests\": \"2a30f5ae8b6256ae9ae51866c287196a3ee781da\", \"crashpad_tests\": \"1cb6801e3fdf3bee55646957dd21e5bfffdbb465\", \"crypto_unittests\": \"96d6979e9f18369ad034ba9d3b1341fa0fc1375d\", \"dbus_unittests\": \"9a79654461ee96f2770aaa215e284be5762ad01c\", \"device_unittests\": \"73371a0950a1e9f6160edacfee28c62805287ddd\", \"display_unittests\": \"51c0fae3a1954cd049a751f69293afbfef4984b8\", \"events_unittests\": \"72e35e7d331bdec75bc2c7679b56857896fb046d\", \"exo_unittests\": \"0e23c4d7584545f9cddea7c60ee020b4e64fd159\", \"extensions_browsertests\": \"758315c3481b5ea51356cd79d3efebb84aa0be30\", \"extensions_unittests\": \"3fdcab86b260ccdf03b901fb3b86d716c9d1edda\", \"filesystem_service_unittests\": \"b5516a03468d3e19bef64393893d742acd62030e\", \"gcm_unit_tests\": \"929f1dc851425610320ee6b955c211a3a61465df\", \"gfx_unittests\": \"88a6b0a51e66d940a86eb3ac4983c30bf5e292ee\", \"gin_unittests\": \"747228f50951c313e883d3ca97f00821c77dc9f7\", \"gl_unittests_ozone\": \"dc051ae9905d77461a8219974bdfd590fa9120cc\", \"google_apis_unittests\": \"907efe9e6ac0a739433a62ba6528342644574239\", \"gpu_unittests\": \"c0937d0c0271a17bef19c4444625d0bd731d995c\", \"gwp_asan_unittests\": \"c7a8437fe89b5fec2179176b83fc85729f74ca2d\", \"interactive_ui_tests\": \"14a961457fda72f2cb103804c505d72e94127771\", \"ipc_tests\": \"31f2681a303f38978ceb8be1e09f7b15de5d4871\", \"jingle_unittests\": \"c738d1a108de454ea8957d04b375112e7acb5d29\", \"keyboard_unittests\": \"fcd4770b4e1730c088ecb0bf72e3b64bf823fce2\", \"latency_unittests\": \"ce29055eaec5a80557cbd123f2dfaab437fcef9e\", \"libjingle_xmpp_unittests\": \"daeecc2d328f91f0cc6d43821dd3fca078f11d7e\", \"liburlpattern_unittests\": \"cf0162613240a2dcb8563cec669e5d6e513b3c5c\", \"media_blink_unittests\": \"e8a46a492456362e25f6b72300ec735c290adb3a\", \"media_unittests\": \"1ba55d086d022f0844a7a59087d38b6c87b592b5\", \"message_center_unittests\": \"560c627b375803c35cd6146e592d32ce176ef4b1\", \"midi_unittests\": \"6e259027fc3e897472aa25c935c205d1f7f43d84\", \"mojo_core_unittests\": \"93bfa3357c41140bebc8215f43976b878074a8ab\", \"mojo_unittests\": \"48a294d54ad5b227856969baafbbc4e2cc55fbda\", \"nacl_helper_nonsfi_unittests\": \"27d0d7725516ff7440dca9a897b9c7e3c3b3a5f0\", \"nacl_loader_unittests\": \"d1a4f73d293fe9764a2e133f95daef1eaf142ce5\", \"native_theme_unittests\": \"5dff907d6c2da14040d2a97f36ba9c4b0680b69c\", \"net_unittests\": \"ea33858770ca9e598256ca82905e10cfea85de1f\", \"ozone_gl_unittests\": \"45114ee0539564993703d491558c748c783c20ce\", \"ozone_unittests\": \"ea8edf723dfe4c37cafa0399f727648d80b0476c\", \"ozone_x11_unittests\": \"f8744b0dbd19ccc5247432957974648ada27f49e\", \"pdf_unittests\": \"dd17c0a76f6aaa4894f5ec54f7c9cc0e173f8eff\", \"perfetto_unittests\": \"37a236427588a0f1320c373bae8c224bee4f77f7\", \"ppapi_unittests\": \"6c28be0bb7af98cc5cd323b97ccd9d7c8c2a4508\", \"printing_unittests\": \"50a8b338e66dc6b0c2a0e9b4d763f90378d3133a\", \"remoting_unittests\": \"37efd8e90cb3f39e822c0bd4e00117c62235e34d\", \"sandbox_linux_unittests\": \"4acb8130c64e89793a6db26e6904966030a7e258\", \"service_manager_unittests\": \"2742c77ce8d5aeff0a727865006a37fed9139909\", \"services_unittests\": \"e59dfcf76635f35137dad69ca4a173d1b4c0ccc7\", \"shell_dialogs_unittests\": \"e315123390179ab49fc2809ba19fe1b168facad7\", \"shell_encryption_unittests\": \"6d31eaa8538792da637fca0035a1d3e899cd8d82\", \"skia_unittests\": \"f507910a0bd18c1bc7f6b46c75f3eec7f853cb4c\", \"snapshot_unittests\": \"4094df4a3ecb47cbc235106503df647f2db705c9\", \"sql_unittests\": \"42a479e020e26e8d5260fd6969ec45f1b7c2c559\", \"storage_unittests\": \"45efec443eefe8ab833610bfd87e835dd82ced1a\", \"sync_integration_tests\": \"85a78c2e2f779eeda69ba3c14f33dd1daeb008ab\", \"traffic_annotation_auditor_unittests\": \"34a3e6469d583aad98b92713e69e97e020ee6a4b\", \"ui_base_unittests\": \"b0135b789ef03dcd94b8640f5e70d0d6d1aeb369\", \"ui_chromeos_unittests\": \"945696b000dbb8928c9cc4b1e5386a3a1ed743c9\", \"ui_touch_selection_unittests\": \"2fa2e51376cb7059dff372dedf542d20118751b8\", \"unit_tests\": \"ab09dfbb064cf894fc987f68949b4b577ca92e3f\", \"url_unittests\": \"09649d9809a76fc3df65d04ef0b9c0c1463754dd\", \"usage_time_limit_unittests\": \"6721d42a2351fefc7be8385534a26ac6f027843a\", \"views_unittests\": \"05262a48c2ce51ccd9cf0ca4bb4548666b22885e\", \"viz_unittests\": \"c6039a2b3c9649020a94373d1a6f970c84e146ce\", \"wm_unittests\": \"23b27f968104f3fc03ea2f44d324364874d75c15\", \"wtf_unittests\": \"3b8e2e63fd53033d73b8a766f39a518fcb9a3e8a\", \"zlib_unittests\": \"20605de5845071bf4391eb184015ceedc544ddb1\"}}, \"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-86-wu7l\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611064817115254",
"status": "COMPLETED",
"status_changed_ts": "1611071091768172",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22257",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c66d101f484f66455fe208f6196b23846437a318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c66d101f484f66455fe208f6196b23846437a318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023099880006491344",
"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/8857669817973996288/+/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:5133c3b6311d9c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611071091772274",
"url": "https://ci.chromium.org/b/8857669817973996288",
"utcnow_ts": "1611071092863490"
}
}
[15:44:52.917] Invocation finished in 1h44m40.640155304s with status SUCCEEDED