[11:56:50.139] New invocation is queued and will start shortly
[11:56:51.231] Starting the invocation (attempt 1)
[11:56:51.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:56:51.266] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:56:51.266] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023017044760950416",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"16df8a5776d666a042c948b894b9e41af64ae6c9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023017044760950416",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/16df8a5776d666a042c948b894b9e41af64ae6c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16df8a5776d666a042c948b894b9e41af64ae6c9",
"gitiles_ref:refs/heads/master"
]
}
[11:56:52.500] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611143811364803",
"id": "8857586982813913344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16df8a5776d666a042c948b894b9e41af64ae6c9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611143812019764",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22271",
"builder:linux-chromeos-dbg",
"buildset:commit/git/16df8a5776d666a042c948b894b9e41af64ae6c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16df8a5776d666a042c948b894b9e41af64ae6c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023017044760950416",
"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/8857586982813913344/+/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": "1611143812019891",
"url": "https://ci.chromium.org/b/8857586982813913344",
"utcnow_ts": "1611143812475679"
}
}
[11:56:52.500] Task URL: https://ci.chromium.org/b/8857586982813913344
[11:56:52.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:2:0) after 1m0s
[11:57:52.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:2:0)
[11:57:52.565] Timer tick, asking Buildbucket for the build status
[11:57:52.661] Build 8857586982813913344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:52.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:3:0) after 1m0s
[11:58:52.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:3:0)
[11:58:52.687] Timer tick, asking Buildbucket for the build status
[11:58:52.757] Build 8857586982813913344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:52.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:4:0) after 1m0s
[11:59:52.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:4:0)
[11:59:52.778] Timer tick, asking Buildbucket for the build status
[11:59:54.424] Build 8857586982813913344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:54.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:5:0) after 1m0s
[12:00:20.579] Received PubSub notification, asking Buildbucket for the build status
[12:00:21.026] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:54.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:5:0)
[12:00:54.477] Timer tick, asking Buildbucket for the build status
[12:00:54.588] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:54.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:7:0) after 1m0s
[12:01:54.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:7:0)
[12:01:54.686] Timer tick, asking Buildbucket for the build status
[12:01:54.785] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:54.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:8:0) after 1m0s
[12:02:54.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:8:0)
[12:02:54.905] Timer tick, asking Buildbucket for the build status
[12:02:54.984] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:54.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:9:0) after 1m0s
[12:03:55.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:9:0)
[12:03:55.362] Timer tick, asking Buildbucket for the build status
[12:03:55.635] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:10:0) after 1m0s
[12:04:55.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:10:0)
[12:04:55.709] Timer tick, asking Buildbucket for the build status
[12:04:55.777] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:55.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:11:0) after 1m0s
[12:05:55.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:11:0)
[12:05:55.873] Timer tick, asking Buildbucket for the build status
[12:05:55.939] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:55.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:12:0) after 1m0s
[12:06:56.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:12:0)
[12:06:56.006] Timer tick, asking Buildbucket for the build status
[12:06:56.156] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:56.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:13:0) after 1m0s
[12:07:56.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:13:0)
[12:07:56.217] Timer tick, asking Buildbucket for the build status
[12:07:56.279] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:56.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:14:0) after 1m0s
[12:08:56.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:14:0)
[12:08:56.292] Timer tick, asking Buildbucket for the build status
[12:08:56.699] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:56.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:15:0) after 1m0s
[12:09:56.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:15:0)
[12:09:56.758] Timer tick, asking Buildbucket for the build status
[12:09:56.837] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:56.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:16:0) after 1m0s
[12:10:56.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:16:0)
[12:10:56.870] Timer tick, asking Buildbucket for the build status
[12:10:56.929] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:56.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:17:0) after 1m0s
[12:11:56.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:17:0)
[12:11:56.923] Timer tick, asking Buildbucket for the build status
[12:11:57.304] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:18:0) after 1m0s
[12:12:57.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:18:0)
[12:12:57.370] Timer tick, asking Buildbucket for the build status
[12:12:57.428] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:57.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:19:0) after 1m0s
[12:13:57.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:19:0)
[12:13:57.479] Timer tick, asking Buildbucket for the build status
[12:13:57.569] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:57.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:20:0) after 1m0s
[12:14:57.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:20:0)
[12:14:57.632] Timer tick, asking Buildbucket for the build status
[12:14:58.144] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:58.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:21:0) after 1m0s
[12:15:58.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:21:0)
[12:15:58.160] Timer tick, asking Buildbucket for the build status
[12:15:58.251] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:58.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:22:0) after 1m0s
[12:16:58.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:22:0)
[12:16:58.271] Timer tick, asking Buildbucket for the build status
[12:16:58.420] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:58.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:23:0) after 1m0s
[12:17:58.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:23:0)
[12:17:58.527] Timer tick, asking Buildbucket for the build status
[12:17:58.596] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:58.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:24:0) after 1m0s
[12:18:58.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:24:0)
[12:18:58.646] Timer tick, asking Buildbucket for the build status
[12:18:58.786] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:58.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:25:0) after 1m0s
[12:19:58.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:25:0)
[12:19:58.837] Timer tick, asking Buildbucket for the build status
[12:19:58.900] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:26:0) after 1m0s
[12:20:58.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:26:0)
[12:20:58.939] Timer tick, asking Buildbucket for the build status
[12:20:59.000] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:59.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:27:0) after 1m0s
[12:21:59.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:27:0)
[12:21:59.153] Timer tick, asking Buildbucket for the build status
[12:21:59.567] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:28:0) after 1m0s
[12:22:59.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:28:0)
[12:22:59.672] Timer tick, asking Buildbucket for the build status
[12:22:59.814] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:59.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:29:0) after 1m0s
[12:23:59.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:29:0)
[12:23:59.952] Timer tick, asking Buildbucket for the build status
[12:24:00.249] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:00.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:30:0) after 1m0s
[12:25:00.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:30:0)
[12:25:00.275] Timer tick, asking Buildbucket for the build status
[12:25:00.652] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:00.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:31:0) after 1m0s
[12:26:00.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:31:0)
[12:26:00.679] Timer tick, asking Buildbucket for the build status
[12:26:00.739] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:00.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:32:0) after 1m0s
[12:27:00.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:32:0)
[12:27:00.764] Timer tick, asking Buildbucket for the build status
[12:27:00.879] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:00.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:33:0) after 1m0s
[12:28:01.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:33:0)
[12:28:01.003] Timer tick, asking Buildbucket for the build status
[12:28:01.187] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:01.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:34:0) after 1m0s
[12:29:01.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:34:0)
[12:29:01.247] Timer tick, asking Buildbucket for the build status
[12:29:01.484] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:01.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:35:0) after 1m0s
[12:30:01.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:35:0)
[12:30:01.762] Timer tick, asking Buildbucket for the build status
[12:30:01.845] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:01.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:36:0) after 1m0s
[12:31:01.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:36:0)
[12:31:01.919] Timer tick, asking Buildbucket for the build status
[12:31:02.001] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:02.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:37:0) after 1m0s
[12:32:02.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:37:0)
[12:32:02.021] Timer tick, asking Buildbucket for the build status
[12:32:02.154] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:02.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:38:0) after 1m0s
[12:33:02.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:38:0)
[12:33:02.255] Timer tick, asking Buildbucket for the build status
[12:33:02.314] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:02.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:39:0) after 1m0s
[12:34:02.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:39:0)
[12:34:02.362] Timer tick, asking Buildbucket for the build status
[12:34:02.514] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:02.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:40:0) after 1m0s
[12:35:02.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:40:0)
[12:35:02.590] Timer tick, asking Buildbucket for the build status
[12:35:02.676] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:02.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:41:0) after 1m0s
[12:36:02.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:41:0)
[12:36:02.708] Timer tick, asking Buildbucket for the build status
[12:36:02.817] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:02.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:42:0) after 1m0s
[12:37:02.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:42:0)
[12:37:02.795] Timer tick, asking Buildbucket for the build status
[12:37:02.911] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:02.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:43:0) after 1m0s
[12:38:02.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:43:0)
[12:38:02.936] Timer tick, asking Buildbucket for the build status
[12:38:03.011] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:03.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:44:0) after 1m0s
[12:39:03.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:44:0)
[12:39:03.038] Timer tick, asking Buildbucket for the build status
[12:39:03.122] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:03.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:45:0) after 1m0s
[12:40:03.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:45:0)
[12:40:03.123] Timer tick, asking Buildbucket for the build status
[12:40:03.445] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:03.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:46:0) after 1m0s
[12:41:03.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:46:0)
[12:41:03.466] Timer tick, asking Buildbucket for the build status
[12:41:03.603] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:03.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:47:0) after 1m0s
[12:42:03.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:47:0)
[12:42:03.635] Timer tick, asking Buildbucket for the build status
[12:42:03.771] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:03.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:48:0) after 1m0s
[12:43:03.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:48:0)
[12:43:03.793] Timer tick, asking Buildbucket for the build status
[12:43:03.861] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:03.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:49:0) after 1m0s
[12:44:03.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:49:0)
[12:44:03.890] Timer tick, asking Buildbucket for the build status
[12:44:04.002] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:04.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:50:0) after 1m0s
[12:45:03.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:50:0)
[12:45:03.988] Timer tick, asking Buildbucket for the build status
[12:45:05.641] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:05.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:51:0) after 1m0s
[12:46:05.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:51:0)
[12:46:05.643] Timer tick, asking Buildbucket for the build status
[12:46:05.944] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:05.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:52:0) after 1m0s
[12:47:06.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:52:0)
[12:47:06.038] Timer tick, asking Buildbucket for the build status
[12:47:06.574] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:06.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:53:0) after 1m0s
[12:48:06.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:53:0)
[12:48:06.658] Timer tick, asking Buildbucket for the build status
[12:48:06.733] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:06.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:54:0) after 1m0s
[12:49:06.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:54:0)
[12:49:06.759] Timer tick, asking Buildbucket for the build status
[12:49:06.960] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:06.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:55:0) after 1m0s
[12:50:06.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:55:0)
[12:50:06.993] Timer tick, asking Buildbucket for the build status
[12:50:07.123] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:07.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:56:0) after 1m0s
[12:51:07.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:56:0)
[12:51:07.175] Timer tick, asking Buildbucket for the build status
[12:51:07.290] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:07.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:57:0) after 1m0s
[12:52:07.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:57:0)
[12:52:07.321] Timer tick, asking Buildbucket for the build status
[12:52:07.637] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:07.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:58:0) after 1m0s
[12:53:08.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:58:0)
[12:53:08.367] Timer tick, asking Buildbucket for the build status
[12:53:08.425] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:08.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:59:0) after 1m0s
[12:54:09.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:59:0)
[12:54:09.000] Timer tick, asking Buildbucket for the build status
[12:54:09.220] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:09.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:60:0) after 1m0s
[12:55:09.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:60:0)
[12:55:09.355] Timer tick, asking Buildbucket for the build status
[12:55:09.742] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:09.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:61:0) after 1m0s
[12:56:09.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:61:0)
[12:56:09.818] Timer tick, asking Buildbucket for the build status
[12:56:09.887] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:09.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:62:0) after 1m0s
[12:57:09.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:62:0)
[12:57:09.954] Timer tick, asking Buildbucket for the build status
[12:57:10.077] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:10.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:63:0) after 1m0s
[12:58:10.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:63:0)
[12:58:10.079] Timer tick, asking Buildbucket for the build status
[12:58:10.181] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:10.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:64:0) after 1m0s
[12:59:10.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:64:0)
[12:59:10.293] Timer tick, asking Buildbucket for the build status
[12:59:10.818] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:10.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:65:0) after 1m0s
[13:00:10.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:65:0)
[13:00:10.849] Timer tick, asking Buildbucket for the build status
[13:00:12.359] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:12.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:66:0) after 1m0s
[13:01:12.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:66:0)
[13:01:12.469] Timer tick, asking Buildbucket for the build status
[13:01:12.628] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:12.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:67:0) after 1m0s
[13:02:12.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:67:0)
[13:02:12.744] Timer tick, asking Buildbucket for the build status
[13:02:12.845] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:12.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:68:0) after 1m0s
[13:03:12.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:68:0)
[13:03:12.910] Timer tick, asking Buildbucket for the build status
[13:03:13.112] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:13.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:69:0) after 1m0s
[13:04:13.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:69:0)
[13:04:13.211] Timer tick, asking Buildbucket for the build status
[13:04:13.556] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:13.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:70:0) after 1m0s
[13:05:14.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:70:0)
[13:05:14.224] Timer tick, asking Buildbucket for the build status
[13:05:14.399] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:14.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:71:0) after 1m0s
[13:06:14.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:71:0)
[13:06:14.591] Timer tick, asking Buildbucket for the build status
[13:06:14.662] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:14.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:72:0) after 1m0s
[13:07:14.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:72:0)
[13:07:14.888] Timer tick, asking Buildbucket for the build status
[13:07:15.106] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:15.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:73:0) after 1m0s
[13:08:15.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:73:0)
[13:08:15.180] Timer tick, asking Buildbucket for the build status
[13:08:15.257] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:15.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:74:0) after 1m0s
[13:09:15.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:74:0)
[13:09:15.393] Timer tick, asking Buildbucket for the build status
[13:09:15.456] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:15.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:75:0) after 1m0s
[13:10:15.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:75:0)
[13:10:15.525] Timer tick, asking Buildbucket for the build status
[13:10:15.641] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:15.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:76:0) after 1m0s
[13:11:15.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:76:0)
[13:11:15.720] Timer tick, asking Buildbucket for the build status
[13:11:15.778] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:15.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:77:0) after 1m0s
[13:12:15.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:77:0)
[13:12:15.846] Timer tick, asking Buildbucket for the build status
[13:12:15.979] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:15.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:78:0) after 1m0s
[13:13:16.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:78:0)
[13:13:16.183] Timer tick, asking Buildbucket for the build status
[13:13:16.331] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:16.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:79:0) after 1m0s
[13:14:16.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:79:0)
[13:14:16.487] Timer tick, asking Buildbucket for the build status
[13:14:16.567] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:16.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:80:0) after 1m0s
[13:15:16.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:80:0)
[13:15:16.638] Timer tick, asking Buildbucket for the build status
[13:15:16.745] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:16.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:81:0) after 1m0s
[13:16:16.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:81:0)
[13:16:16.770] Timer tick, asking Buildbucket for the build status
[13:16:17.292] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:17.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:82:0) after 1m0s
[13:17:17.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:82:0)
[13:17:17.294] Timer tick, asking Buildbucket for the build status
[13:17:17.532] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:17.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:83:0) after 1m0s
[13:18:17.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:83:0)
[13:18:17.577] Timer tick, asking Buildbucket for the build status
[13:18:17.667] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:17.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:84:0) after 1m0s
[13:19:17.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:84:0)
[13:19:17.680] Timer tick, asking Buildbucket for the build status
[13:19:17.758] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:17.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:85:0) after 1m0s
[13:20:17.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:85:0)
[13:20:17.834] Timer tick, asking Buildbucket for the build status
[13:20:17.978] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:17.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:86:0) after 1m0s
[13:21:18.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:86:0)
[13:21:18.056] Timer tick, asking Buildbucket for the build status
[13:21:18.325] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:18.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:87:0) after 1m0s
[13:22:18.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:87:0)
[13:22:18.379] Timer tick, asking Buildbucket for the build status
[13:22:18.619] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:18.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:88:0) after 1m0s
[13:23:18.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:88:0)
[13:23:18.674] Timer tick, asking Buildbucket for the build status
[13:23:20.233] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:20.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:89:0) after 1m0s
[13:24:20.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:89:0)
[13:24:20.269] Timer tick, asking Buildbucket for the build status
[13:24:20.391] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:90:0) after 1m0s
[13:25:20.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:90:0)
[13:25:20.425] Timer tick, asking Buildbucket for the build status
[13:25:20.527] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:91:0) after 1m0s
[13:26:20.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:91:0)
[13:26:20.582] Timer tick, asking Buildbucket for the build status
[13:26:20.995] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:20.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:92:0) after 1m0s
[13:27:20.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:92:0)
[13:27:20.999] Timer tick, asking Buildbucket for the build status
[13:27:21.497] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:21.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:93:0) after 1m0s
[13:28:21.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:93:0)
[13:28:21.493] Timer tick, asking Buildbucket for the build status
[13:28:21.797] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:21.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:94:0) after 1m0s
[13:29:21.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:94:0)
[13:29:21.850] Timer tick, asking Buildbucket for the build status
[13:29:21.928] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:21.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:95:0) after 1m0s
[13:30:21.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:95:0)
[13:30:21.948] Timer tick, asking Buildbucket for the build status
[13:30:22.053] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:22.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:96:0) after 1m0s
[13:31:22.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:96:0)
[13:31:22.127] Timer tick, asking Buildbucket for the build status
[13:31:22.333] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:22.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:97:0) after 1m0s
[13:32:22.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:97:0)
[13:32:22.491] Timer tick, asking Buildbucket for the build status
[13:32:22.599] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:22.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:98:0) after 1m0s
[13:33:22.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:98:0)
[13:33:22.686] Timer tick, asking Buildbucket for the build status
[13:33:22.765] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:22.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:99:0) after 1m0s
[13:34:22.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:99:0)
[13:34:22.849] Timer tick, asking Buildbucket for the build status
[13:34:22.919] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:22.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:100:0) after 1m0s
[13:35:22.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:100:0)
[13:35:22.981] Timer tick, asking Buildbucket for the build status
[13:35:23.064] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:23.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:101:0) after 1m0s
[13:36:23.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:101:0)
[13:36:23.173] Timer tick, asking Buildbucket for the build status
[13:36:23.247] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:23.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:102:0) after 1m0s
[13:37:23.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:102:0)
[13:37:23.315] Timer tick, asking Buildbucket for the build status
[13:37:23.489] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:23.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:103:0) after 1m0s
[13:38:23.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:103:0)
[13:38:23.527] Timer tick, asking Buildbucket for the build status
[13:38:23.601] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:23.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:104:0) after 1m0s
[13:39:23.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:104:0)
[13:39:23.661] Timer tick, asking Buildbucket for the build status
[13:39:24.101] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:24.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:105:0) after 1m0s
[13:40:24.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:105:0)
[13:40:24.181] Timer tick, asking Buildbucket for the build status
[13:40:24.522] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:24.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:106:0) after 1m0s
[13:41:24.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:106:0)
[13:41:24.547] Timer tick, asking Buildbucket for the build status
[13:41:25.025] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:25.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:107:0) after 1m0s
[13:42:25.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:107:0)
[13:42:25.059] Timer tick, asking Buildbucket for the build status
[13:42:25.580] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:25.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:108:0) after 1m0s
[13:43:25.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:108:0)
[13:43:25.600] Timer tick, asking Buildbucket for the build status
[13:43:25.662] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:25.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:109:0) after 1m0s
[13:44:25.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:109:0)
[13:44:25.686] Timer tick, asking Buildbucket for the build status
[13:44:25.953] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:25.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:110:0) after 1m0s
[13:45:25.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:110:0)
[13:45:25.935] Timer tick, asking Buildbucket for the build status
[13:45:26.503] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:26.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:111:0) after 1m0s
[13:46:26.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:111:0)
[13:46:26.529] Timer tick, asking Buildbucket for the build status
[13:46:26.995] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:26.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:112:0) after 1m0s
[13:47:27.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:112:0)
[13:47:27.021] Timer tick, asking Buildbucket for the build status
[13:47:27.134] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:27.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:113:0) after 1m0s
[13:48:27.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:113:0)
[13:48:27.177] Timer tick, asking Buildbucket for the build status
[13:48:27.290] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:27.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:114:0) after 1m0s
[13:49:27.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:114:0)
[13:49:27.327] Timer tick, asking Buildbucket for the build status
[13:49:27.632] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:27.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:115:0) after 1m0s
[13:50:27.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:115:0)
[13:50:27.683] Timer tick, asking Buildbucket for the build status
[13:50:27.784] Build 8857586982813913344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:27.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023017044760950416:116:0) after 1m0s
[13:51:02.251] Received PubSub notification, asking Buildbucket for the build status
[13:51:02.358] Build 8857586982813913344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:51:02.358] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611150660911333",
"created_by": "project:chromium",
"created_ts": "1611143811364803",
"failure_reason": "BUILD_FAILURE",
"id": "8857586982813913344",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16df8a5776d666a042c948b894b9e41af64ae6c9\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileSystemProviderApiTest.Unmount\"}, \"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\": \"16df8a5776d666a042c948b894b9e41af64ae6c9\", \"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\": 22271, \"got_angle_revision\": \"186fe99034047745c4a626935b3ac2cce557f8f3\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"16df8a5776d666a042c948b894b9e41af64ae6c9\", \"got_revision_cp\": \"refs/heads/master@{#845132}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"22be66d4071284ffeeef424adc068e9e400835ab\", \"got_v8_revision\": \"0c46f3e0ded50952ed6f46254bbd16776f532aa0\", \"got_v8_revision_cp\": \"refs/heads/9.0.20@{#1}\", \"got_webrtc_revision\": \"6dcbcead3da45604b84aef9d9496689fef5ff8f5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33041}\", \"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\": \"16df8a5776d666a042c948b894b9e41af64ae6c9\", \"swarm_hashes_refs/heads/master(at){#845132}_without_patch\": {\"absl_hardening_tests\": \"c6c6ff326cd1e2a9eac74a1e537deead6f308ebb\", \"accessibility_unittests\": \"64d37afa4e6fb030b8a4f67f6a3eb273e1951adf\", \"angle_unittests\": \"f886aea38950aaa74cee6f58531ae4930b8e60cd\", \"app_list_unittests\": \"0b4f5ad80627648491e4ae0126fe834c50fc5341\", \"app_shell_unittests\": \"3096a43a370b63fc159e535703b60b7bbb822fd5\", \"ash_unittests\": \"8a55b4cea4e0750bf375d5783ef9c5cff4d29026\", \"aura_unittests\": \"9ad5293e4c8e8c7048c898b2c17455fc87a18f65\", \"base_unittests\": \"aabae1311c173f5288f6e4afcc9a985c6ab2e803\", \"base_util_unittests\": \"98451fc9e800f6d12a162453ae00d8faeb53feb2\", \"blink_common_unittests\": \"b2162d92455f93f1e6bb73ba632739bb8135558c\", \"blink_fuzzer_unittests\": \"907669341e6b5e53210b75e66af841e803842b41\", \"blink_heap_unittests\": \"4394baf6762fe6fd3f77f7964759a18b69a49d5e\", \"blink_platform_unittests\": \"0ffbbf09dfa56ecd415d3bc0ebbb5b492c6f604f\", \"blink_unittests\": \"331a7be60038c042132cc2f948d98cf049da26e0\", \"boringssl_crypto_tests\": \"84be9ab84f9ebc93a77e45c82ed6faaa6383ddd1\", \"boringssl_ssl_tests\": \"3bc44467a74ccefac76c356126cf6c70f02f5b45\", \"browser_tests\": \"bf0a7ae9e61fa068499670e27837121628b05de0\", \"capture_unittests\": \"eeb16ff8b09d33b47b7870b4a6e46ce81d26dfba\", \"cast_unittests\": \"1d752276b6db654af3214f9a74e609f3726b2a29\", \"cc_unittests\": \"ecaf1a9b99a65e0d46931075b326bc2526abe6fb\", \"chrome_app_unittests\": \"aabd75aabf87e3c3fd89bfd5b21623da5427d7e4\", \"chromedriver_unittests\": \"b1abc1fc90ce8c2b7349bcc18b677ddcdadff3c8\", \"chromeos_components_unittests\": \"d7d02a2a72bc7d31ce10f91335288ff117641827\", \"chromeos_unittests\": \"80a0951b4cef5d43890a4783e10edc7d35caff13\", \"components_browsertests\": \"93e67387a6079fce6f6697dbbf266dd1942575d4\", \"components_unittests\": \"58431f1bb6feb96d2a80e30c3e4ab03b2678bd30\", \"compositor_unittests\": \"e9d7a18825ce78f9d982ef40219c8182c29fdc74\", \"content_browsertests\": \"f8903374ea4c764f2315e29955701893e378875f\", \"content_unittests\": \"dfb36c964c211a4a9177f504d2195231be8e7baf\", \"crashpad_tests\": \"c6a182bcc06512fc2b986e66a53cab3f47670dbf\", \"crypto_unittests\": \"1460e2228733efaa86534152edf8b8ae8c1623c9\", \"dbus_unittests\": \"bcd6f171543553465fdb8b6d70c1d8d36fc72370\", \"device_unittests\": \"0864f1f3d5d4b38386955b2dd530c798581c586d\", \"display_unittests\": \"99ed1ad0fda21623a050f0472efd2724a454a652\", \"events_unittests\": \"88d26108e766a60fc013c432d2fc30c5fd019c02\", \"exo_unittests\": \"0796e67f0b03fd0d0ab6ab0c40ec4bb7ee92ebe5\", \"extensions_browsertests\": \"21bd4cc532a5891d77e054f25b2e31c1e120e432\", \"extensions_unittests\": \"1d564f75b7ecf5b0ba8363d01d641cf51287d081\", \"filesystem_service_unittests\": \"ad705e0256ead4e73d299ca6c0130659e2834340\", \"gcm_unit_tests\": \"25059df9fdbe2181ee5da6566311e7fedfd9c6d6\", \"gfx_unittests\": \"0cd5da6b8693f3fba9d6f320c2c3e5b8fece575c\", \"gin_unittests\": \"9dae09657f00dac0ea14bd9e7be05f7345b1a583\", \"gl_unittests_ozone\": \"a9567130e26a34d4138b571317f76774948c06cb\", \"google_apis_unittests\": \"3156988f087862f8ba9f5720723881109ea99dac\", \"gpu_unittests\": \"3e598ab78fee68394d0f7c98ba77656f071a382a\", \"gwp_asan_unittests\": \"f7e3df00a0aee77f4c8c7cf26e15d70d4bc6da58\", \"interactive_ui_tests\": \"424e7cb4868f4494163401d0c06ee71add89fdc0\", \"ipc_tests\": \"9360a64e300fdb97d4882fa29282f10779360d77\", \"jingle_unittests\": \"4a8413c7f7a844787d6c9a51513d46e9479ca8bd\", \"keyboard_unittests\": \"bf3b86339d005686ffcc173be3f1e6143dfa536a\", \"latency_unittests\": \"470f268e952a5c74c1d2a5e8b785bedf11a51f55\", \"libjingle_xmpp_unittests\": \"8c3aa2199873d694ab22aaf044e51bb5b96ce8e6\", \"liburlpattern_unittests\": \"7d4553bc0bc40528d731aa79a2a430223f17ffca\", \"media_blink_unittests\": \"1461a2d369885f29625e8cdc7548cc630553d047\", \"media_unittests\": \"af3f26e889e3a7fa0953f2e275b20f4a1cecc398\", \"message_center_unittests\": \"2ce59b6e666059f35a2fcd9f244ee2936a33bc23\", \"midi_unittests\": \"a803553a2d511e2351635a263481d1e10ef344ff\", \"mojo_core_unittests\": \"608153ae3a6aa35ebd242cb8af9b1bdac78f9745\", \"mojo_unittests\": \"87c72aaf6ecd5422e7ff90418c637b828dd4bf39\", \"nacl_helper_nonsfi_unittests\": \"bd8d54b68ac9dcc06ccb181564ca84529686f0e2\", \"nacl_loader_unittests\": \"ca9f0885de4966a3706f8349b5bd60938e472ca0\", \"native_theme_unittests\": \"90d5c87ca4fcc0800f2aaf4cdf05b4e33835f518\", \"net_unittests\": \"f0ca0afd376b1a295012cf1929200902198c7aa6\", \"ozone_gl_unittests\": \"0d6458e3b7f5c9145feddf886a1ed3ca13f48be9\", \"ozone_unittests\": \"5fee745ac45dfb34dd52381e538e9c61b16e2b1f\", \"ozone_x11_unittests\": \"eeeae8e2807d0ee1375854f81c09b1812aaf4aaa\", \"pdf_unittests\": \"4368badc48fdb208a55fe72e95aefa24a4ca7e5c\", \"perfetto_unittests\": \"bf2cac930d23bb9af8235a7904606d162b7960d0\", \"ppapi_unittests\": \"a1312d8b3e352efc6ced75a8e46b53d11d24a052\", \"printing_unittests\": \"95ddc9e950fb996dc7e4f52be0c45934fa5fd6bd\", \"remoting_unittests\": \"9824b9777edb525066a80fc15e8ebb703acc8166\", \"sandbox_linux_unittests\": \"673dbf8db2b459080ca8327ed42ba6f73dae5e6a\", \"service_manager_unittests\": \"c7b43ba69d8b802e938e79f81a482f44c6aff343\", \"services_unittests\": \"1a820b2968edb08445782252d9bb59b937070af4\", \"shell_dialogs_unittests\": \"d3311e3e94dc4d01bf02bc674259e0a38586b432\", \"shell_encryption_unittests\": \"f481d0019ed6350d9340a6b851e50b05d82f0d6b\", \"skia_unittests\": \"0c12312655fa9b6445efec1ff078a01e1f975eb7\", \"snapshot_unittests\": \"628c33339730fe38b89be3e1fc3d1b198f643329\", \"sql_unittests\": \"1b4b9258f87784f8e44724b2ad176e537164567f\", \"storage_unittests\": \"ca4883616114edd24832524b09812da62c0a723c\", \"sync_integration_tests\": \"a0737011ceb2738f9de9d29577e5072e16a9a86a\", \"traffic_annotation_auditor_unittests\": \"fc0b875efa80480e74902c4d3eaafb9efa814e86\", \"ui_base_unittests\": \"2378fff2d74eaf8d35cf80c5457727ae23328fa1\", \"ui_chromeos_unittests\": \"894daaff0bc96594d256681f92552d79098a90b2\", \"ui_touch_selection_unittests\": \"5906e29647c0d2de65dcb282b582e420570aed20\", \"unit_tests\": \"02438bdb5822579a3915c539123b0799778bc21e\", \"url_unittests\": \"42f1b263f1a43da856d37ec3d03dc33db0ec801b\", \"usage_time_limit_unittests\": \"56dd40f122ce7084d718d9766015b500cd22bb57\", \"views_unittests\": \"9d4f7d12ec0799c761a579bd5fa842bdbb3598eb\", \"viz_unittests\": \"9b3088b3a4b1f5fa97e7a1b35fd0eca5ef037031\", \"wm_unittests\": \"074136a84a20dba5680d82d4c8cf4c5604862bf4\", \"wtf_unittests\": \"643b6427a5fd5f1512ec35d8f3d994ee1601742b\", \"zlib_unittests\": \"0ca84e1f58816c5e0309c5dc7f506ca5e884b068\"}}, \"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\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileSystemProviderApiTest.Unmount\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611144019852331",
"status": "COMPLETED",
"status_changed_ts": "1611144020268225",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22271",
"builder:linux-chromeos-dbg",
"buildset:commit/git/16df8a5776d666a042c948b894b9e41af64ae6c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16df8a5776d666a042c948b894b9e41af64ae6c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023017044760950416",
"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/8857586982813913344/+/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:5138791d90e24a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611150661723002",
"url": "https://ci.chromium.org/b/8857586982813913344",
"utcnow_ts": "1611150662346646"
}
}
[13:51:02.358] Invocation finished in 1h54m12.235525035s with status FAILED