[18:06:46.713] New invocation is queued and will start shortly
[18:06:47.884] Starting the invocation (attempt 1)
[18:06:47.919] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:06:47.919] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:06:47.919] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019822876140934096",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2d53e91102a362c9b417d04dd667ac326fad4135\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9019822876140934096",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/2d53e91102a362c9b417d04dd667ac326fad4135",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d53e91102a362c9b417d04dd667ac326fad4135",
"gitiles_ref:refs/heads/master"
]
}
[18:06:48.960] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614190008010572",
"id": "8854392814119577088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d53e91102a362c9b417d04dd667ac326fad4135\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614190008708959",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22764",
"builder:linux-chromeos-dbg",
"buildset:commit/git/2d53e91102a362c9b417d04dd667ac326fad4135",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d53e91102a362c9b417d04dd667ac326fad4135",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019822876140934096",
"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/8854392814119577088/+/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": "1614190008709099",
"url": "https://ci.chromium.org/b/8854392814119577088",
"utcnow_ts": "1614190008928270"
}
}
[18:06:48.960] Task URL: https://ci.chromium.org/b/8854392814119577088
[18:06:48.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:2:0) after 1m0s
[18:06:50.535] Received PubSub notification, asking Buildbucket for the build status
[18:06:50.630] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:49.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:2:0)
[18:07:49.006] Timer tick, asking Buildbucket for the build status
[18:07:49.290] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:49.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:4:0) after 1m0s
[18:08:49.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:4:0)
[18:08:49.303] Timer tick, asking Buildbucket for the build status
[18:08:49.431] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:49.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:5:0) after 1m0s
[18:09:49.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:5:0)
[18:09:49.459] Timer tick, asking Buildbucket for the build status
[18:09:49.545] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:49.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:6:0) after 1m0s
[18:10:49.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:6:0)
[18:10:49.615] Timer tick, asking Buildbucket for the build status
[18:10:49.973] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:49.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:7:0) after 1m0s
[18:11:49.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:7:0)
[18:11:49.979] Timer tick, asking Buildbucket for the build status
[18:11:50.078] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:50.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:8:0) after 1m0s
[18:12:50.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:8:0)
[18:12:50.128] Timer tick, asking Buildbucket for the build status
[18:12:50.199] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:50.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:9:0) after 1m0s
[18:13:50.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:9:0)
[18:13:50.219] Timer tick, asking Buildbucket for the build status
[18:13:50.297] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:50.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:10:0) after 1m0s
[18:14:50.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:10:0)
[18:14:50.361] Timer tick, asking Buildbucket for the build status
[18:14:50.499] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:50.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:11:0) after 1m0s
[18:15:50.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:11:0)
[18:15:50.630] Timer tick, asking Buildbucket for the build status
[18:15:50.824] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:50.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:12:0) after 1m0s
[18:16:50.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:12:0)
[18:16:50.829] Timer tick, asking Buildbucket for the build status
[18:16:51.009] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:51.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:13:0) after 1m0s
[18:17:51.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:13:0)
[18:17:51.054] Timer tick, asking Buildbucket for the build status
[18:17:51.345] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:51.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:14:0) after 1m0s
[18:18:51.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:14:0)
[18:18:51.443] Timer tick, asking Buildbucket for the build status
[18:18:51.531] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:51.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:15:0) after 1m0s
[18:19:51.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:15:0)
[18:19:51.648] Timer tick, asking Buildbucket for the build status
[18:19:51.720] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:51.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:16:0) after 1m0s
[18:20:51.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:16:0)
[18:20:51.737] Timer tick, asking Buildbucket for the build status
[18:20:51.876] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:51.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:17:0) after 1m0s
[18:21:51.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:17:0)
[18:21:51.940] Timer tick, asking Buildbucket for the build status
[18:21:52.035] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:52.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:18:0) after 1m0s
[18:22:52.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:18:0)
[18:22:52.111] Timer tick, asking Buildbucket for the build status
[18:22:52.181] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:19:0) after 1m0s
[18:23:52.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:19:0)
[18:23:52.272] Timer tick, asking Buildbucket for the build status
[18:23:52.405] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:20:0) after 1m0s
[18:24:52.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:20:0)
[18:24:52.484] Timer tick, asking Buildbucket for the build status
[18:24:52.568] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:52.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:21:0) after 1m0s
[18:25:52.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:21:0)
[18:25:52.640] Timer tick, asking Buildbucket for the build status
[18:25:52.927] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:52.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:22:0) after 1m0s
[18:26:52.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:22:0)
[18:26:52.993] Timer tick, asking Buildbucket for the build status
[18:26:53.112] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:23:0) after 1m0s
[18:27:53.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:23:0)
[18:27:53.150] Timer tick, asking Buildbucket for the build status
[18:27:53.223] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:53.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:24:0) after 1m0s
[18:28:53.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:24:0)
[18:28:53.270] Timer tick, asking Buildbucket for the build status
[18:28:53.336] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:53.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:25:0) after 1m0s
[18:29:53.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:25:0)
[18:29:53.390] Timer tick, asking Buildbucket for the build status
[18:29:53.467] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:53.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:26:0) after 1m0s
[18:30:53.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:26:0)
[18:30:53.486] Timer tick, asking Buildbucket for the build status
[18:30:53.570] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:53.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:27:0) after 1m0s
[18:31:53.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:27:0)
[18:31:53.643] Timer tick, asking Buildbucket for the build status
[18:31:53.735] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:53.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:28:0) after 1m0s
[18:32:53.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:28:0)
[18:32:53.850] Timer tick, asking Buildbucket for the build status
[18:32:54.380] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:54.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:29:0) after 1m0s
[18:33:54.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:29:0)
[18:33:54.436] Timer tick, asking Buildbucket for the build status
[18:33:54.763] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:54.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:30:0) after 1m0s
[18:34:54.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:30:0)
[18:34:54.780] Timer tick, asking Buildbucket for the build status
[18:34:55.119] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:55.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:31:0) after 1m0s
[18:35:55.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:31:0)
[18:35:55.285] Timer tick, asking Buildbucket for the build status
[18:35:55.368] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:55.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:32:0) after 1m0s
[18:36:55.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:32:0)
[18:36:55.406] Timer tick, asking Buildbucket for the build status
[18:36:55.506] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:55.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:33:0) after 1m0s
[18:37:55.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:33:0)
[18:37:55.594] Timer tick, asking Buildbucket for the build status
[18:37:55.760] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:55.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:34:0) after 1m0s
[18:38:55.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:34:0)
[18:38:55.796] Timer tick, asking Buildbucket for the build status
[18:38:55.878] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:55.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:35:0) after 1m0s
[18:39:55.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:35:0)
[18:39:55.924] Timer tick, asking Buildbucket for the build status
[18:39:56.036] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:56.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:36:0) after 1m0s
[18:40:56.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:36:0)
[18:40:56.219] Timer tick, asking Buildbucket for the build status
[18:40:56.326] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:56.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:37:0) after 1m0s
[18:41:56.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:37:0)
[18:41:56.316] Timer tick, asking Buildbucket for the build status
[18:41:56.610] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:56.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:38:0) after 1m0s
[18:42:56.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:38:0)
[18:42:56.689] Timer tick, asking Buildbucket for the build status
[18:42:56.918] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:56.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:39:0) after 1m0s
[18:43:56.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:39:0)
[18:43:56.925] Timer tick, asking Buildbucket for the build status
[18:43:56.996] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:56.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:40:0) after 1m0s
[18:44:57.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:40:0)
[18:44:57.059] Timer tick, asking Buildbucket for the build status
[18:44:57.737] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:57.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:41:0) after 1m0s
[18:45:58.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:41:0)
[18:45:58.124] Timer tick, asking Buildbucket for the build status
[18:45:58.221] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:42:0) after 1m0s
[18:46:58.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:42:0)
[18:46:58.289] Timer tick, asking Buildbucket for the build status
[18:46:58.413] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:58.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:43:0) after 1m0s
[18:47:58.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:43:0)
[18:47:58.473] Timer tick, asking Buildbucket for the build status
[18:47:58.550] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:58.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:44:0) after 1m0s
[18:48:58.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:44:0)
[18:48:58.823] Timer tick, asking Buildbucket for the build status
[18:48:58.909] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:58.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:45:0) after 1m0s
[18:49:58.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:45:0)
[18:49:58.937] Timer tick, asking Buildbucket for the build status
[18:49:59.033] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:59.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:46:0) after 1m0s
[18:50:59.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:46:0)
[18:50:59.075] Timer tick, asking Buildbucket for the build status
[18:51:00.088] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:00.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:47:0) after 1m0s
[18:52:00.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:47:0)
[18:52:00.116] Timer tick, asking Buildbucket for the build status
[18:52:00.255] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:48:0) after 1m0s
[18:53:00.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:48:0)
[18:53:00.295] Timer tick, asking Buildbucket for the build status
[18:53:00.376] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:00.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:49:0) after 1m0s
[18:54:01.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:49:0)
[18:54:01.018] Timer tick, asking Buildbucket for the build status
[18:54:01.099] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:01.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:50:0) after 1m0s
[18:55:02.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:50:0)
[18:55:02.013] Timer tick, asking Buildbucket for the build status
[18:55:02.290] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:02.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:51:0) after 1m0s
[18:56:02.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:51:0)
[18:56:02.318] Timer tick, asking Buildbucket for the build status
[18:56:02.583] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:02.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:52:0) after 1m0s
[18:57:02.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:52:0)
[18:57:02.663] Timer tick, asking Buildbucket for the build status
[18:57:02.820] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:02.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:53:0) after 1m0s
[18:58:02.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:53:0)
[18:58:02.860] Timer tick, asking Buildbucket for the build status
[18:58:03.009] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:03.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:54:0) after 1m0s
[18:59:03.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:54:0)
[18:59:03.051] Timer tick, asking Buildbucket for the build status
[18:59:03.698] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:03.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:55:0) after 1m0s
[19:00:03.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:55:0)
[19:00:03.995] Timer tick, asking Buildbucket for the build status
[19:00:04.112] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:04.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:56:0) after 1m0s
[19:01:04.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:56:0)
[19:01:04.405] Timer tick, asking Buildbucket for the build status
[19:01:04.511] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:04.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:57:0) after 1m0s
[19:02:04.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:57:0)
[19:02:04.632] Timer tick, asking Buildbucket for the build status
[19:02:04.877] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:04.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:58:0) after 1m0s
[19:03:04.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:58:0)
[19:03:04.939] Timer tick, asking Buildbucket for the build status
[19:03:05.016] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:05.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:59:0) after 1m0s
[19:04:05.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:59:0)
[19:04:05.388] Timer tick, asking Buildbucket for the build status
[19:04:05.463] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:05.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:60:0) after 1m0s
[19:05:05.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:60:0)
[19:05:05.576] Timer tick, asking Buildbucket for the build status
[19:05:05.669] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:05.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:61:0) after 1m0s
[19:06:05.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:61:0)
[19:06:05.742] Timer tick, asking Buildbucket for the build status
[19:06:05.844] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:05.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:62:0) after 1m0s
[19:07:05.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:62:0)
[19:07:05.841] Timer tick, asking Buildbucket for the build status
[19:07:06.110] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:06.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:63:0) after 1m0s
[19:08:06.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:63:0)
[19:08:06.132] Timer tick, asking Buildbucket for the build status
[19:08:06.464] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:06.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:64:0) after 1m0s
[19:09:06.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:64:0)
[19:09:06.504] Timer tick, asking Buildbucket for the build status
[19:09:06.624] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:06.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:65:0) after 1m0s
[19:10:06.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:65:0)
[19:10:06.623] Timer tick, asking Buildbucket for the build status
[19:10:07.068] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:07.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:66:0) after 1m0s
[19:11:07.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:66:0)
[19:11:07.202] Timer tick, asking Buildbucket for the build status
[19:11:07.325] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:07.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:67:0) after 1m0s
[19:12:07.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:67:0)
[19:12:07.391] Timer tick, asking Buildbucket for the build status
[19:12:07.539] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:07.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:68:0) after 1m0s
[19:13:07.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:68:0)
[19:13:07.586] Timer tick, asking Buildbucket for the build status
[19:13:07.668] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:07.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:69:0) after 1m0s
[19:14:07.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:69:0)
[19:14:07.758] Timer tick, asking Buildbucket for the build status
[19:14:07.848] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:07.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:70:0) after 1m0s
[19:15:07.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:70:0)
[19:15:07.917] Timer tick, asking Buildbucket for the build status
[19:15:08.049] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:08.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:71:0) after 1m0s
[19:16:08.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:71:0)
[19:16:08.085] Timer tick, asking Buildbucket for the build status
[19:16:08.175] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:08.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:72:0) after 1m0s
[19:17:08.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:72:0)
[19:17:08.220] Timer tick, asking Buildbucket for the build status
[19:17:08.319] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:08.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:73:0) after 1m0s
[19:18:08.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:73:0)
[19:18:08.390] Timer tick, asking Buildbucket for the build status
[19:18:08.584] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:08.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:74:0) after 1m0s
[19:19:08.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:74:0)
[19:19:08.605] Timer tick, asking Buildbucket for the build status
[19:19:08.704] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:08.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:75:0) after 1m0s
[19:20:08.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:75:0)
[19:20:08.741] Timer tick, asking Buildbucket for the build status
[19:20:08.833] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:08.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:76:0) after 1m0s
[19:21:08.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:76:0)
[19:21:08.883] Timer tick, asking Buildbucket for the build status
[19:21:08.985] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:08.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:77:0) after 1m0s
[19:22:09.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:77:0)
[19:22:09.055] Timer tick, asking Buildbucket for the build status
[19:22:09.429] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:09.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:78:0) after 1m0s
[19:23:09.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:78:0)
[19:23:09.510] Timer tick, asking Buildbucket for the build status
[19:23:09.590] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:09.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:79:0) after 1m0s
[19:24:09.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:79:0)
[19:24:09.582] Timer tick, asking Buildbucket for the build status
[19:24:09.703] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:09.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:80:0) after 1m0s
[19:25:09.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:80:0)
[19:25:09.877] Timer tick, asking Buildbucket for the build status
[19:25:10.010] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:10.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:81:0) after 1m0s
[19:26:10.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:81:0)
[19:26:10.108] Timer tick, asking Buildbucket for the build status
[19:26:10.199] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:10.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:82:0) after 1m0s
[19:27:10.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:82:0)
[19:27:10.218] Timer tick, asking Buildbucket for the build status
[19:27:10.326] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:83:0) after 1m0s
[19:28:10.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:83:0)
[19:28:10.428] Timer tick, asking Buildbucket for the build status
[19:28:10.507] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:10.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:84:0) after 1m0s
[19:29:10.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:84:0)
[19:29:10.670] Timer tick, asking Buildbucket for the build status
[19:29:10.744] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:10.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:85:0) after 1m0s
[19:30:10.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:85:0)
[19:30:10.807] Timer tick, asking Buildbucket for the build status
[19:30:11.126] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:11.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:86:0) after 1m0s
[19:31:11.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:86:0)
[19:31:11.186] Timer tick, asking Buildbucket for the build status
[19:31:11.418] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:11.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:87:0) after 1m0s
[19:32:11.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:87:0)
[19:32:11.504] Timer tick, asking Buildbucket for the build status
[19:32:11.824] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:11.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:88:0) after 1m0s
[19:33:12.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:88:0)
[19:33:12.151] Timer tick, asking Buildbucket for the build status
[19:33:12.240] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:12.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:89:0) after 1m0s
[19:34:12.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:89:0)
[19:34:12.265] Timer tick, asking Buildbucket for the build status
[19:34:12.518] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:12.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:90:0) after 1m0s
[19:35:12.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:90:0)
[19:35:12.540] Timer tick, asking Buildbucket for the build status
[19:35:12.623] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:12.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:91:0) after 1m0s
[19:36:12.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:91:0)
[19:36:12.656] Timer tick, asking Buildbucket for the build status
[19:36:12.747] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:12.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:92:0) after 1m0s
[19:37:12.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:92:0)
[19:37:12.770] Timer tick, asking Buildbucket for the build status
[19:37:12.872] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:12.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:93:0) after 1m0s
[19:38:12.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:93:0)
[19:38:12.942] Timer tick, asking Buildbucket for the build status
[19:38:13.196] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:13.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:94:0) after 1m0s
[19:39:13.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:94:0)
[19:39:13.301] Timer tick, asking Buildbucket for the build status
[19:39:13.579] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:13.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:95:0) after 1m0s
[19:40:13.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:95:0)
[19:40:13.639] Timer tick, asking Buildbucket for the build status
[19:40:14.099] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:14.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:96:0) after 1m0s
[19:41:14.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:96:0)
[19:41:14.191] Timer tick, asking Buildbucket for the build status
[19:41:14.266] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:14.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:97:0) after 1m0s
[19:42:14.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:97:0)
[19:42:14.321] Timer tick, asking Buildbucket for the build status
[19:42:14.575] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:14.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:98:0) after 1m0s
[19:43:14.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:98:0)
[19:43:14.807] Timer tick, asking Buildbucket for the build status
[19:43:14.902] Build 8854392814119577088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:14.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019822876140934096:99:0) after 1m0s
[19:43:38.750] Received PubSub notification, asking Buildbucket for the build status
[19:43:38.828] Build 8854392814119577088: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:43:38.828] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614195818422554",
"created_by": "project:chromium",
"created_ts": "1614190008010572",
"failure_reason": "BUILD_FAILURE",
"id": "8854392814119577088",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d53e91102a362c9b417d04dd667ac326fad4135\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SigninToUserProfileSwitchTest.LoginAsNewUser\"}, \"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\": \"2d53e91102a362c9b417d04dd667ac326fad4135\", \"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-east1-d-8-76-83di\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22764, \"got_angle_revision\": \"9b9d683e9cc3ceaa07cc501e5667779db791c0cd\", \"got_dawn_revision\": \"018b9b917c1d15accd3854b9248b8b6c1a91f6fe\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"2d53e91102a362c9b417d04dd667ac326fad4135\", \"got_revision_cp\": \"refs/heads/master@{#857247}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"7a343dccaf574db270f30b6dd6426c884d572649\", \"got_v8_revision_cp\": \"refs/heads/9.0.255@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"2d53e91102a362c9b417d04dd667ac326fad4135\", \"swarm_hashes_refs/heads/master(at){#857247}_without_patch\": {\"absl_hardening_tests\": \"4bfa55f6ff204cd777d1cc1bd86f256f3689a7b3\", \"accessibility_unittests\": \"4e77957f7bd39377af603e76549f564a24c47640\", \"angle_unittests\": \"bf4ca6d90af5fa1a7d481708f49f2e06bb128e68\", \"app_list_unittests\": \"820bf6cffccd8fcc98d55e30f8791afe37564122\", \"app_shell_unittests\": \"2f5675cda1ccbd0be6a4e04d11c61c7135b976fb\", \"ash_unittests\": \"702a16652b5008ff940a478ff7fec376fa4b09ea\", \"aura_unittests\": \"d481aaad6dd01eac5503d14e3ff16fe70cc2d518\", \"base_unittests\": \"ea969c3c1047608a3729eb6a767bf673c134251c\", \"base_util_unittests\": \"8cba35ef45f4d3f14ab8ce41c840c1a625dc99d5\", \"blink_common_unittests\": \"babc442c6e298ecc07d8432bc0c39cf8085c398b\", \"blink_fuzzer_unittests\": \"ca26a2f0957725aea853fb32cf42ce415f633c0a\", \"blink_heap_unittests\": \"99bf7b48c489b62d73b50b0bdf7f2c801ea7e67d\", \"blink_platform_unittests\": \"819dae65d8d35c028d7b68ea56c3315581481dd9\", \"blink_unittests\": \"42de88a5a92285274d658110631eb7b4bd165925\", \"boringssl_crypto_tests\": \"b545064c28349bb07327b412676c064c02137b27\", \"boringssl_ssl_tests\": \"c37afb3615db0d3c2230e86479d2b93ab3bc5b55\", \"browser_tests\": \"fc4b56c8e54fdb9fb9862d01d19f8f37f536a18d\", \"capture_unittests\": \"a40beebc404685b87b47c2d60cf0e08a9037c7c0\", \"cast_unittests\": \"219940e97398d12e8579a9afa89860fdbfc99b80\", \"cc_unittests\": \"02a1dcccc6c2e21e96127e7c08972c698a0b8fdf\", \"chrome_app_unittests\": \"90d76150e4cf8c71c7f4e503625b555164701cb1\", \"chromedriver_unittests\": \"c2dcfc8590b753a82b21f2a4f25b552e1e027c6c\", \"chromeos_components_unittests\": \"37a7d11b74923959b5e5427c0fb1d0e40aa6609c\", \"chromeos_unittests\": \"264ed95ba7b4225b51b1bda92ed97f978dc743f0\", \"color_unittests\": \"483b56ae47917e80c02f04261e59c42c060c57df\", \"components_browsertests\": \"7710ca1ce2c5385ef833c3476f0defb3a50f9737\", \"components_unittests\": \"2768ce0e4afb9d7e96bc6c34ecfc1989acf074d2\", \"compositor_unittests\": \"16480696c7b4a84f9950c3c2614a6ca9df47e1d1\", \"content_browsertests\": \"d94e814696ff3fea35cb7c67c6b8d4ec13556107\", \"content_unittests\": \"fdd25048c7e40b0fdf93eea3fcbd68054015626a\", \"crashpad_tests\": \"953c12b3c5b8c82aece7f3cc1bbe7dffdb94057b\", \"crypto_unittests\": \"d0b31e36ae9693a2494ae4d0736b2d2e7bfc785e\", \"dbus_unittests\": \"9c91a9afc2bff0be4db33a0af18e83da39949a9d\", \"device_unittests\": \"27f8c5cc68363df25c58ab7f75b05b9e1edcd542\", \"display_unittests\": \"7110ade5e05e322f2a594e4fa9079f8f929ff0f4\", \"events_unittests\": \"b52a0fb21fc27afb36bea4e42cf1f17ca5dc7369\", \"exo_unittests\": \"0f100b370d7f6a7a0a640b1b3be110820f021e8a\", \"extensions_browsertests\": \"b8350a691aa9dc5cae2f612e4c79ad57ae359637\", \"extensions_unittests\": \"8d1c547853ccdf886cf237323be68f40b6257736\", \"filesystem_service_unittests\": \"4a4cf2825a21f9acdd90a74fac21b4f46dab296a\", \"gcm_unit_tests\": \"7385e430669db70dc0b637de6903e1e702d2cafa\", \"gfx_unittests\": \"30545a8c1af31be2d729a0890c51e8bbf5bea3dc\", \"gin_unittests\": \"92c6fab65379e6db0f39cb3108e347a918a4f0ab\", \"gl_unittests_ozone\": \"17072ce557717ab3c696ea4f84723c6e023cb4dd\", \"google_apis_unittests\": \"8c2efbeb32ac95f5930588c225db524b069e5ecb\", \"gpu_unittests\": \"3ec7b4f467b1a18038db891cf7e33de8dc82f4bc\", \"gwp_asan_unittests\": \"76f5eb126d759d5d979e2cd74a491484624d8ff9\", \"interactive_ui_tests\": \"7afacfd149d1694e22d4f54b48510c4a0b7b0aec\", \"ipc_tests\": \"c4ba1cd93113d4d8d32e499e8a179fb5e82fac86\", \"jingle_unittests\": \"abe6e47a6fb39c661187f8e3d20df24039a8a4bb\", \"keyboard_unittests\": \"196e82856800396400604690828fe5c04178f4ca\", \"latency_unittests\": \"d2b1b30dd90ee15a1d792765acac9c48f17b7529\", \"libjingle_xmpp_unittests\": \"a67b153cbd2d1c2d141d648cc924bd397740a049\", \"liburlpattern_unittests\": \"7ad0332d9db6b37ffd1a7463c234774831be25df\", \"media_blink_unittests\": \"280b63d6e2530d57935c03389f8ed767047cf3f6\", \"media_unittests\": \"2b0f25658f100366a0cfbdf963dccded3abe5264\", \"message_center_unittests\": \"3dab5e787ae1c14377df64f483a44fafe2386ab8\", \"midi_unittests\": \"3bfbd36fc3e5b4ef9a4acefb9704547d3e332093\", \"mojo_core_unittests\": \"869a575ae29bb21d9a3e05fff0c439f46ea814fc\", \"mojo_unittests\": \"d748fc9e275fbb6af799803bf0fbfe40fc084985\", \"nacl_helper_nonsfi_unittests\": \"99030aff13e4c820950f7d60813a4f2a1dedc9f5\", \"nacl_loader_unittests\": \"4052a264daf3a502ac50accd13efd08f793dca2c\", \"native_theme_unittests\": \"73172698a7db6095f61728e9cb0f63c160c5466c\", \"net_unittests\": \"61e56cbf6663f78c9abc2ad693256756d6f4ec7d\", \"ozone_gl_unittests\": \"e0a76a396fdf2598f643481010a3d0e22e6a8c24\", \"ozone_unittests\": \"6a631f2f64dc79e43a37e807ff5bad612da7f0d6\", \"ozone_x11_unittests\": \"49d230a9a10ec6865bb4035b0b1ecc40cc6f7ac6\", \"pdf_unittests\": \"a838a6bde17aee12220d1e12f74474ad53d811db\", \"perfetto_unittests\": \"e989e914a6c2cd7d9e8df34db9a6517c389de895\", \"ppapi_unittests\": \"bea8134c17d3309aa09c90d766c6cd5577ae4eb1\", \"printing_unittests\": \"95b84be835a703379367d549bb9dd1064ae07fc2\", \"remoting_unittests\": \"70c986b1d27f9fc343f90512b05ff0ace5e5fc9c\", \"sandbox_linux_unittests\": \"1561b9a9851bf411b32077f19f672996a2ad5610\", \"service_manager_unittests\": \"294787d2912192523264b88270db771db102338b\", \"services_unittests\": \"0eff5e06317ae3416a24bd2eb168c0f26ee1e381\", \"shell_dialogs_unittests\": \"2bd587bb8dc71fcc4fc6e6c2b1ce5bb8c37c10b7\", \"shell_encryption_unittests\": \"8252153c7140e2fcea7e2110e9c7ed99d6e15035\", \"skia_unittests\": \"0d3a4cfc65655a6d5d87fc70d15b7c79dcf2c886\", \"snapshot_unittests\": \"da5c4a7c238a2555f9c5f1c9838e698819aff007\", \"sql_unittests\": \"59de4a0ad0abf295f26e6809efff8f10edc82471\", \"storage_unittests\": \"6c6a6d8c4e67d9d64f9991b76d50f62219e2acc6\", \"sync_integration_tests\": \"7049cb1d9e2f9d0e499cd50888ef51882f47edb0\", \"traffic_annotation_auditor_unittests\": \"4840d4ac3933e5fb0d69cd116d1daa5450e6b540\", \"ui_base_unittests\": \"aefaabb75c734a78baed98d06264a168522fdcbe\", \"ui_chromeos_unittests\": \"859bf423adca73dcde09cd53f49b06672b46b120\", \"ui_touch_selection_unittests\": \"3c4a947c6465cad84ce3a04a9c1b545f50264c40\", \"unit_tests\": \"29c684f7b5ad19cda31e1d4861af526cef3a29b5\", \"url_unittests\": \"fe25841be8f95d8b71af86ad99619f648df868cd\", \"usage_time_limit_unittests\": \"2fc3dd7ff79b004c7b5905f5cdd65c5e10ae366f\", \"views_unittests\": \"a9db962773daabaff3b9221517cf5d1dee86f871\", \"viz_unittests\": \"20f3d71afeeae34aa8b8a582488820f20a8cf58d\", \"wm_unittests\": \"d6b0028da88182b67dca7a12ca5f4c71ea1851c0\", \"wtf_unittests\": \"d04831372091005c1562eba1b0cfad590e8a102a\", \"zlib_unittests\": \"f3f2431143345822a19adc0c331e375464de1f04\"}}, \"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-east1-d-8-76-83di\"], \"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\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SigninToUserProfileSwitchTest.LoginAsNewUser\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614190009748341",
"status": "COMPLETED",
"status_changed_ts": "1614190010345009",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22764",
"builder:linux-chromeos-dbg",
"buildset:commit/git/2d53e91102a362c9b417d04dd667ac326fad4135",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d53e91102a362c9b417d04dd667ac326fad4135",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019822876140934096",
"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/8854392814119577088/+/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:51ee0a6455765610",
"user_agent:luci-scheduler"
],
"updated_ts": "1614195818423000",
"url": "https://ci.chromium.org/b/8854392814119577088",
"utcnow_ts": "1614195818819634"
}
}
[19:43:38.828] Invocation finished in 1h36m52.13036338s with status FAILED