[14:27:20.849] New invocation is queued and will start shortly
[14:27:21.920] Starting the invocation (attempt 1)
[14:27:21.955] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:27:21.955] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:27:21.955] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019746084585244864",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d1eacad252409cfc6ede47a2858262f17310ed2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9019746084585244864",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/4d1eacad252409cfc6ede47a2858262f17310ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d1eacad252409cfc6ede47a2858262f17310ed2",
"gitiles_ref:refs/heads/master"
]
}
[14:27:23.531] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614263242098875",
"id": "8854316022611655600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d1eacad252409cfc6ede47a2858262f17310ed2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614263243337051",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22778",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4d1eacad252409cfc6ede47a2858262f17310ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d1eacad252409cfc6ede47a2858262f17310ed2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019746084585244864",
"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/8854316022611655600/+/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": "1614263243337187",
"url": "https://ci.chromium.org/b/8854316022611655600",
"utcnow_ts": "1614263243522860"
}
}
[14:27:23.531] Task URL: https://ci.chromium.org/b/8854316022611655600
[14:27:23.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:2:0) after 1m0s
[14:28:23.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:2:0)
[14:28:23.570] Timer tick, asking Buildbucket for the build status
[14:28:23.655] Build 8854316022611655600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:23.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:3:0) after 1m0s
[14:29:23.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:3:0)
[14:29:23.681] Timer tick, asking Buildbucket for the build status
[14:29:23.763] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:23.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:4:0) after 1m0s
[14:29:31.285] Received PubSub notification, asking Buildbucket for the build status
[14:29:31.476] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:23.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:4:0)
[14:30:23.806] Timer tick, asking Buildbucket for the build status
[14:30:23.917] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:23.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:6:0) after 1m0s
[14:31:23.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:6:0)
[14:31:23.927] Timer tick, asking Buildbucket for the build status
[14:31:24.011] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:24.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:7:0) after 1m0s
[14:32:24.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:7:0)
[14:32:24.051] Timer tick, asking Buildbucket for the build status
[14:32:24.114] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:24.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:8:0) after 1m0s
[14:33:24.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:8:0)
[14:33:24.237] Timer tick, asking Buildbucket for the build status
[14:33:24.394] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:24.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:9:0) after 1m0s
[14:34:24.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:9:0)
[14:34:24.421] Timer tick, asking Buildbucket for the build status
[14:34:24.499] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:24.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:10:0) after 1m0s
[14:35:24.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:10:0)
[14:35:24.525] Timer tick, asking Buildbucket for the build status
[14:35:24.595] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:24.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:11:0) after 1m0s
[14:36:24.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:11:0)
[14:36:24.739] Timer tick, asking Buildbucket for the build status
[14:36:24.819] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:24.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:12:0) after 1m0s
[14:37:25.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:12:0)
[14:37:25.468] Timer tick, asking Buildbucket for the build status
[14:37:25.697] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:25.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:13:0) after 1m0s
[14:38:25.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:13:0)
[14:38:25.713] Timer tick, asking Buildbucket for the build status
[14:38:25.795] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:25.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:14:0) after 1m0s
[14:39:25.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:14:0)
[14:39:25.770] Timer tick, asking Buildbucket for the build status
[14:39:25.869] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:25.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:15:0) after 1m0s
[14:40:25.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:15:0)
[14:40:25.959] Timer tick, asking Buildbucket for the build status
[14:40:26.030] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:26.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:16:0) after 1m0s
[14:41:26.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:16:0)
[14:41:26.052] Timer tick, asking Buildbucket for the build status
[14:41:26.172] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:26.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:17:0) after 1m0s
[14:42:26.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:17:0)
[14:42:26.193] Timer tick, asking Buildbucket for the build status
[14:42:26.286] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:26.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:18:0) after 1m0s
[14:43:26.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:18:0)
[14:43:26.349] Timer tick, asking Buildbucket for the build status
[14:43:26.559] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:26.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:19:0) after 1m0s
[14:44:26.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:19:0)
[14:44:26.657] Timer tick, asking Buildbucket for the build status
[14:44:26.781] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:26.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:20:0) after 1m0s
[14:45:26.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:20:0)
[14:45:26.824] Timer tick, asking Buildbucket for the build status
[14:45:26.992] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:26.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:21:0) after 1m0s
[14:46:27.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:21:0)
[14:46:27.035] Timer tick, asking Buildbucket for the build status
[14:46:27.530] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:27.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:22:0) after 1m0s
[14:47:27.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:22:0)
[14:47:27.637] Timer tick, asking Buildbucket for the build status
[14:47:27.855] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:27.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:23:0) after 1m0s
[14:48:27.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:23:0)
[14:48:27.961] Timer tick, asking Buildbucket for the build status
[14:48:28.046] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:28.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:24:0) after 1m0s
[14:49:29.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:24:0)
[14:49:29.099] Timer tick, asking Buildbucket for the build status
[14:49:29.355] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:29.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:25:0) after 1m0s
[14:50:29.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:25:0)
[14:50:29.431] Timer tick, asking Buildbucket for the build status
[14:50:29.752] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:29.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:26:0) after 1m0s
[14:51:29.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:26:0)
[14:51:29.860] Timer tick, asking Buildbucket for the build status
[14:51:29.945] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:29.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:27:0) after 1m0s
[14:52:30.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:27:0)
[14:52:30.000] Timer tick, asking Buildbucket for the build status
[14:52:30.121] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:30.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:28:0) after 1m0s
[14:53:30.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:28:0)
[14:53:30.301] Timer tick, asking Buildbucket for the build status
[14:53:30.372] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:30.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:29:0) after 1m0s
[14:54:30.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:29:0)
[14:54:30.426] Timer tick, asking Buildbucket for the build status
[14:54:30.587] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:30.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:30:0) after 1m0s
[14:55:30.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:30:0)
[14:55:30.677] Timer tick, asking Buildbucket for the build status
[14:55:30.765] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:30.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:31:0) after 1m0s
[14:56:30.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:31:0)
[14:56:30.810] Timer tick, asking Buildbucket for the build status
[14:56:30.926] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:30.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:32:0) after 1m0s
[14:57:31.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:32:0)
[14:57:31.016] Timer tick, asking Buildbucket for the build status
[14:57:31.420] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:31.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:33:0) after 1m0s
[14:58:31.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:33:0)
[14:58:31.440] Timer tick, asking Buildbucket for the build status
[14:58:31.630] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:31.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:34:0) after 1m0s
[14:59:31.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:34:0)
[14:59:31.647] Timer tick, asking Buildbucket for the build status
[14:59:31.720] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:31.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:35:0) after 1m0s
[15:00:32.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:35:0)
[15:00:32.220] Timer tick, asking Buildbucket for the build status
[15:00:32.690] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:36:0) after 1m0s
[15:01:32.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:36:0)
[15:01:32.737] Timer tick, asking Buildbucket for the build status
[15:01:33.112] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:33.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:37:0) after 1m0s
[15:02:33.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:37:0)
[15:02:33.122] Timer tick, asking Buildbucket for the build status
[15:02:33.201] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:33.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:38:0) after 1m0s
[15:03:33.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:38:0)
[15:03:33.224] Timer tick, asking Buildbucket for the build status
[15:03:33.341] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:33.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:39:0) after 1m0s
[15:04:33.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:39:0)
[15:04:33.345] Timer tick, asking Buildbucket for the build status
[15:04:33.458] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:33.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:40:0) after 1m0s
[15:05:33.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:40:0)
[15:05:33.529] Timer tick, asking Buildbucket for the build status
[15:05:33.615] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:33.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:41:0) after 1m0s
[15:06:33.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:41:0)
[15:06:33.683] Timer tick, asking Buildbucket for the build status
[15:06:33.994] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:33.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:42:0) after 1m0s
[15:07:33.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:42:0)
[15:07:33.987] Timer tick, asking Buildbucket for the build status
[15:07:34.079] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:34.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:43:0) after 1m0s
[15:08:34.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:43:0)
[15:08:34.122] Timer tick, asking Buildbucket for the build status
[15:08:34.255] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:34.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:44:0) after 1m0s
[15:09:34.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:44:0)
[15:09:34.251] Timer tick, asking Buildbucket for the build status
[15:09:34.596] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:34.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:45:0) after 1m0s
[15:10:34.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:45:0)
[15:10:34.616] Timer tick, asking Buildbucket for the build status
[15:10:34.743] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:34.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:46:0) after 1m0s
[15:11:34.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:46:0)
[15:11:34.788] Timer tick, asking Buildbucket for the build status
[15:11:35.057] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:35.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:47:0) after 1m0s
[15:12:35.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:47:0)
[15:12:35.122] Timer tick, asking Buildbucket for the build status
[15:12:35.356] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:35.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:48:0) after 1m0s
[15:13:35.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:48:0)
[15:13:35.383] Timer tick, asking Buildbucket for the build status
[15:13:35.495] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:35.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:49:0) after 1m0s
[15:14:35.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:49:0)
[15:14:35.503] Timer tick, asking Buildbucket for the build status
[15:14:35.584] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:35.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:50:0) after 1m0s
[15:15:35.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:50:0)
[15:15:35.638] Timer tick, asking Buildbucket for the build status
[15:15:40.615] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:40.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:51:0) after 1m0s
[15:16:40.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:51:0)
[15:16:40.599] Timer tick, asking Buildbucket for the build status
[15:16:40.832] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:40.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:52:0) after 1m0s
[15:17:40.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:52:0)
[15:17:40.832] Timer tick, asking Buildbucket for the build status
[15:17:40.986] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:40.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:53:0) after 1m0s
[15:18:40.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:53:0)
[15:18:40.969] Timer tick, asking Buildbucket for the build status
[15:18:41.398] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:41.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:54:0) after 1m0s
[15:19:41.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:54:0)
[15:19:41.475] Timer tick, asking Buildbucket for the build status
[15:19:41.555] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:55:0) after 1m0s
[15:20:41.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:55:0)
[15:20:41.577] Timer tick, asking Buildbucket for the build status
[15:20:41.671] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:41.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:56:0) after 1m0s
[15:21:41.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:56:0)
[15:21:41.972] Timer tick, asking Buildbucket for the build status
[15:21:42.115] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:42.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:57:0) after 1m0s
[15:22:42.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:57:0)
[15:22:42.151] Timer tick, asking Buildbucket for the build status
[15:22:42.265] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:42.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:58:0) after 1m0s
[15:23:42.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:58:0)
[15:23:42.332] Timer tick, asking Buildbucket for the build status
[15:23:42.427] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:42.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:59:0) after 1m0s
[15:24:42.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:59:0)
[15:24:42.428] Timer tick, asking Buildbucket for the build status
[15:24:42.518] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:42.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:60:0) after 1m0s
[15:25:42.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:60:0)
[15:25:42.581] Timer tick, asking Buildbucket for the build status
[15:25:42.698] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:42.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:61:0) after 1m0s
[15:26:42.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:61:0)
[15:26:42.726] Timer tick, asking Buildbucket for the build status
[15:26:42.796] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:42.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:62:0) after 1m0s
[15:27:42.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:62:0)
[15:27:42.834] Timer tick, asking Buildbucket for the build status
[15:27:42.928] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:42.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:63:0) after 1m0s
[15:28:42.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:63:0)
[15:28:42.996] Timer tick, asking Buildbucket for the build status
[15:28:43.562] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:43.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:64:0) after 1m0s
[15:29:43.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:64:0)
[15:29:43.622] Timer tick, asking Buildbucket for the build status
[15:29:43.717] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:43.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:65:0) after 1m0s
[15:30:43.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:65:0)
[15:30:43.843] Timer tick, asking Buildbucket for the build status
[15:30:44.132] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:44.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:66:0) after 1m0s
[15:31:44.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:66:0)
[15:31:44.194] Timer tick, asking Buildbucket for the build status
[15:31:44.300] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:44.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:67:0) after 1m0s
[15:32:44.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:67:0)
[15:32:44.422] Timer tick, asking Buildbucket for the build status
[15:32:44.507] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:44.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:68:0) after 1m0s
[15:33:44.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:68:0)
[15:33:44.550] Timer tick, asking Buildbucket for the build status
[15:33:44.652] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:44.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:69:0) after 1m0s
[15:34:44.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:69:0)
[15:34:44.690] Timer tick, asking Buildbucket for the build status
[15:34:44.932] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:44.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:70:0) after 1m0s
[15:35:45.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:70:0)
[15:35:45.001] Timer tick, asking Buildbucket for the build status
[15:35:45.113] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:45.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:71:0) after 1m0s
[15:36:45.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:71:0)
[15:36:45.130] Timer tick, asking Buildbucket for the build status
[15:36:45.370] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:45.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:72:0) after 1m0s
[15:37:45.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:72:0)
[15:37:45.392] Timer tick, asking Buildbucket for the build status
[15:37:45.463] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:45.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:73:0) after 1m0s
[15:38:45.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:73:0)
[15:38:45.455] Timer tick, asking Buildbucket for the build status
[15:38:45.548] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:74:0) after 1m0s
[15:39:45.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:74:0)
[15:39:45.601] Timer tick, asking Buildbucket for the build status
[15:39:45.759] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:45.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:75:0) after 1m0s
[15:40:45.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:75:0)
[15:40:45.750] Timer tick, asking Buildbucket for the build status
[15:40:45.858] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:45.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:76:0) after 1m0s
[15:41:45.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:76:0)
[15:41:45.895] Timer tick, asking Buildbucket for the build status
[15:41:45.987] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:77:0) after 1m0s
[15:42:46.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:77:0)
[15:42:46.011] Timer tick, asking Buildbucket for the build status
[15:42:46.109] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:46.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:78:0) after 1m0s
[15:43:46.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:78:0)
[15:43:46.174] Timer tick, asking Buildbucket for the build status
[15:43:46.257] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:46.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:79:0) after 1m0s
[15:44:47.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:79:0)
[15:44:47.255] Timer tick, asking Buildbucket for the build status
[15:44:47.398] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:47.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:80:0) after 1m0s
[15:45:47.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:80:0)
[15:45:47.582] Timer tick, asking Buildbucket for the build status
[15:45:47.669] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:47.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:81:0) after 1m0s
[15:46:48.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:81:0)
[15:46:48.083] Timer tick, asking Buildbucket for the build status
[15:46:48.195] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:48.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:82:0) after 1m0s
[15:47:48.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:82:0)
[15:47:48.370] Timer tick, asking Buildbucket for the build status
[15:47:48.476] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:48.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:83:0) after 1m0s
[15:48:48.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:83:0)
[15:48:48.588] Timer tick, asking Buildbucket for the build status
[15:48:48.679] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:48.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:84:0) after 1m0s
[15:49:48.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:84:0)
[15:49:48.796] Timer tick, asking Buildbucket for the build status
[15:49:48.905] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:48.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:85:0) after 1m0s
[15:50:49.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:85:0)
[15:50:49.114] Timer tick, asking Buildbucket for the build status
[15:50:49.216] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:49.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:86:0) after 1m0s
[15:51:49.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:86:0)
[15:51:49.411] Timer tick, asking Buildbucket for the build status
[15:51:49.575] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:49.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:87:0) after 1m0s
[15:52:49.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:87:0)
[15:52:49.781] Timer tick, asking Buildbucket for the build status
[15:52:49.883] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:49.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:88:0) after 1m0s
[15:53:49.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:88:0)
[15:53:49.941] Timer tick, asking Buildbucket for the build status
[15:53:50.057] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:50.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:89:0) after 1m0s
[15:54:50.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:89:0)
[15:54:50.088] Timer tick, asking Buildbucket for the build status
[15:54:50.336] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:50.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:90:0) after 1m0s
[15:55:50.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:90:0)
[15:55:50.382] Timer tick, asking Buildbucket for the build status
[15:55:50.458] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:50.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:91:0) after 1m0s
[15:56:51.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:91:0)
[15:56:51.211] Timer tick, asking Buildbucket for the build status
[15:56:51.391] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:51.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:92:0) after 1m0s
[15:57:51.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:92:0)
[15:57:51.476] Timer tick, asking Buildbucket for the build status
[15:57:51.561] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:51.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:93:0) after 1m0s
[15:58:51.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:93:0)
[15:58:51.710] Timer tick, asking Buildbucket for the build status
[15:58:51.955] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:51.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:94:0) after 1m0s
[15:59:52.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:94:0)
[15:59:52.019] Timer tick, asking Buildbucket for the build status
[15:59:52.289] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:52.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:95:0) after 1m0s
[16:00:52.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:95:0)
[16:00:52.310] Timer tick, asking Buildbucket for the build status
[16:00:52.722] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:52.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:96:0) after 1m0s
[16:01:52.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:96:0)
[16:01:52.766] Timer tick, asking Buildbucket for the build status
[16:01:53.015] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:53.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:97:0) after 1m0s
[16:02:53.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:97:0)
[16:02:53.101] Timer tick, asking Buildbucket for the build status
[16:02:53.208] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:53.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:98:0) after 1m0s
[16:03:53.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:98:0)
[16:03:53.233] Timer tick, asking Buildbucket for the build status
[16:03:53.839] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:53.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:99:0) after 1m0s
[16:04:53.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:99:0)
[16:04:53.905] Timer tick, asking Buildbucket for the build status
[16:04:54.013] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:100:0) after 1m0s
[16:05:54.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:100:0)
[16:05:54.106] Timer tick, asking Buildbucket for the build status
[16:05:54.231] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:54.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:101:0) after 1m0s
[16:06:54.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:101:0)
[16:06:54.350] Timer tick, asking Buildbucket for the build status
[16:06:54.448] Build 8854316022611655600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:54.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9019746084585244864:102:0) after 1m0s
[16:07:00.248] Received PubSub notification, asking Buildbucket for the build status
[16:07:00.446] Build 8854316022611655600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:07:00.446] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614269219541495",
"created_by": "project:chromium",
"created_ts": "1614263242098875",
"failure_reason": "BUILD_FAILURE",
"id": "8854316022611655600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d1eacad252409cfc6ede47a2858262f17310ed2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- UpdateRequiredScreenTest.TestUpdateOverMeteredNetwork\\n\\n- BetterUpdateScreenTest.TestBatteryWarningDuringUpdateStages\\n\\n- MediaRouterIntegrationBrowserTest.CloseOnError\\n\\n- BetterUpdateScreenTest.TestUpdateCompletedRebootNeeded\\n\\n- ...9 more failure(s) (13 total)...\\n\\n**ash_unittests** failed because of:\\n\\n- UpdateNotificationControllerTest.VisibilityAfterUpdateRequiringFactoryReset\\n\\n- AssistantAlarmTimerControllerTest.TimerNotificationHasExpectedTitleV2\\n\\n- PciePeripheralNotificationControllerTest.GuestNotificationTbtOnly\\n\\n- UpdateNotificationControllerTest.VisibilityAfterRollback\\n\\n- ...9 more failure(s) (13 total)...\"}, \"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\": \"4d1eacad252409cfc6ede47a2858262f17310ed2\", \"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\": 22778, \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"4d1eacad252409cfc6ede47a2858262f17310ed2\", \"got_revision_cp\": \"refs/heads/master@{#857655}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd5127b11e04b7f49031b2412625c36e4a86c3da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33340}\", \"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\": \"4d1eacad252409cfc6ede47a2858262f17310ed2\", \"swarm_hashes_refs/heads/master(at){#857655}_without_patch\": {\"absl_hardening_tests\": \"c37d27dc62f72c9b0b7de0c8322c3f80725e22dc\", \"accessibility_unittests\": \"f52e55fbe5803314e913d39ba87991477506ac42\", \"angle_unittests\": \"9e91ac4eefe134f48a79d7b44d7f3c5cb807c90a\", \"app_list_unittests\": \"22fb877f6784869e7d21e8292c3c7fa63ab44462\", \"app_shell_unittests\": \"90245dea331e4d2683afca9eb0e6573844733a6f\", \"ash_unittests\": \"bd56f7a15b9a99f98868359dfd58a4941d061c9c\", \"aura_unittests\": \"5fc0a15fe923894a376e0a74536d66cd1de71466\", \"base_unittests\": \"74097f0a96f8751d593fc8ec7d9fdf65927dbd32\", \"base_util_unittests\": \"5664d36acd913d840fcd0791a2b27707e91efef4\", \"blink_common_unittests\": \"c5189c98290dc7bf26afcf3c134abd96023a0b62\", \"blink_fuzzer_unittests\": \"8c4b08a8679e45198867a25468c72617379a414c\", \"blink_heap_unittests\": \"37e0afd5520dd6058a59d1ba405b8c835b8331ed\", \"blink_platform_unittests\": \"084602cedec95a4cfb512686994a97d66284ab4a\", \"blink_unittests\": \"11a605609b775542b18dd14c9949d0dce8f064b3\", \"boringssl_crypto_tests\": \"e6834c10b8a1bf5940a056503748522d5afc9493\", \"boringssl_ssl_tests\": \"5210678687ead529b7cf5744a95ab485148e7d44\", \"browser_tests\": \"25b1ef622538e4f29026286da7d34538bb535ba1\", \"capture_unittests\": \"fe9b6d1e04c4089ecf83993d5468561acc90b0cc\", \"cast_unittests\": \"e32de4a0799c9c09c3c5d735db9f736c04988b54\", \"cc_unittests\": \"004770aebe3edf9a14bb1808d50d5ad3a2eca431\", \"chrome_app_unittests\": \"a7b56ad2a7f890ab43cafcbbe1fdf1bf7b9dce54\", \"chromedriver_unittests\": \"ab49555f85434678fcb6af93f76c19d41bf97a01\", \"chromeos_components_unittests\": \"8e3a327f152e0ec6937e159c18c1eca52467522f\", \"chromeos_unittests\": \"89b691a6b01667ab9eb82023201a0dc4752a3cbf\", \"color_unittests\": \"da36142689a0acd404a36440dbf3edc39aa70fe1\", \"components_browsertests\": \"1b10a0982a8f61652378f970c5ebe56fe0287652\", \"components_unittests\": \"38e70ba4f5409f52375031cd38b4b7c5c19d417e\", \"compositor_unittests\": \"503963ff70ce903531c2d3e2cc94608a184956cb\", \"content_browsertests\": \"bb7f4aba4393847bb1dd93cefcbdddcfa1ac57bc\", \"content_unittests\": \"1b4f0dd0e5c20e617f4a3891ab27fea8578444ee\", \"crashpad_tests\": \"467313d0a8be737482739ad45ce1c3e969d4fbca\", \"crypto_unittests\": \"98ac0839990ad46435c359bb153f5e6a6bdb84a7\", \"dbus_unittests\": \"a39b0e23556107fad3a1e6202aa20200b3601bf2\", \"device_unittests\": \"a45eb35315cbf0b5a05136b5b41739bf9f89ad32\", \"display_unittests\": \"ce445f8c5e8c141acde4b5a17024d2ac49047f68\", \"events_unittests\": \"7ed8e7ffbbdf2526f98104fbdde814af60dfb16f\", \"exo_unittests\": \"8159d8c081891273c4b9ae87e4f708a3805f68a5\", \"extensions_browsertests\": \"bd58a247d3c2b81ea1e211ccd92472aeb221e862\", \"extensions_unittests\": \"b462374bdb01bcd02ab88e0496d6ceeb09999ab2\", \"filesystem_service_unittests\": \"3fbe2e21d1960c9b2a216d3a9fb10f8a4a5c9616\", \"gcm_unit_tests\": \"92323f72a0dbb56eecba5205a4e857bb84248bbe\", \"gfx_unittests\": \"c3d6bcb092d4632e2385183ab5ba5ee0eca877e3\", \"gin_unittests\": \"3810e3636fded700ad962209f3dcdbe21bfd6cff\", \"gl_unittests_ozone\": \"b47da9249e8499d17dea95eff371fdfd505e107b\", \"google_apis_unittests\": \"71e26feb3035345d1238be4f2573c9a5b7ce714c\", \"gpu_unittests\": \"4119796d9494cdfc22fdc6f1582e925a3f0c0d4b\", \"gwp_asan_unittests\": \"3afb3e9f9b24ed4ffd9f4153bfa42622dfb29ce3\", \"interactive_ui_tests\": \"9054726a30e39a1165c278f652fe2a14554d5c7a\", \"ipc_tests\": \"b7d00ad2ac72b714253c41f419705cb26f194ab2\", \"jingle_unittests\": \"84c3e441c1a463d3c879619489a1cfb5fdc93bb7\", \"keyboard_unittests\": \"ec7702a7ced97c4b1abbff606ac24d7dd6dd236a\", \"latency_unittests\": \"1f28afbe790c0bc576258e75b62a0c70a6b5333a\", \"libjingle_xmpp_unittests\": \"7aa70eb4acbf6e96890c4d2eb37eead9911e335d\", \"liburlpattern_unittests\": \"6e72edeb295ec1d54b90b1fd178fad3469bab96d\", \"media_blink_unittests\": \"698d1a301db69a23185ecc7cfdbd61fc3fabfd67\", \"media_unittests\": \"b65b3137e415d34a0e5befc1fa4a081f520e0da2\", \"message_center_unittests\": \"f6cc3b27b7be44321af3adbafac9dcb1666428dd\", \"midi_unittests\": \"e253c00cbff23fef19f1fe9adb78150201fcf3d1\", \"mojo_core_unittests\": \"c2e59274eb17bd0f46d500452fbd37820a0b8831\", \"mojo_unittests\": \"fb9706d5b5f674d2a05fdf14387fcfa04a2f1d26\", \"nacl_helper_nonsfi_unittests\": \"904af00ccd177f1cbb5d8d7a6c1cc086d2c43fe4\", \"nacl_loader_unittests\": \"e35decbbe734cd2efcad17b6b8fd4b639e498c9a\", \"native_theme_unittests\": \"24b3a5d1030b19bb8395b4653623198f9568a7fb\", \"net_unittests\": \"2a97df4fde8839c270f181d7ea7717b6f4aac7b0\", \"ozone_gl_unittests\": \"421a52760fc56a1339877de0fe84a0eb1370a1d4\", \"ozone_unittests\": \"818f4cccf420cf9aa12a5fb4649fbd2b10428b2b\", \"ozone_x11_unittests\": \"2d4336236de16feaf4f9df402e00236031681427\", \"pdf_unittests\": \"6316372f0eda72105fa4d26f317ef735d6e237f0\", \"perfetto_unittests\": \"0e014f2a64bcb59bb89636f133ac69a88616b83a\", \"ppapi_unittests\": \"37de9a61e69933964229306de2a998175ae359a9\", \"printing_unittests\": \"749e75e77486c4336dd8df03e7441f3f00b4f74a\", \"remoting_unittests\": \"51fb0396868dee351fe58585a83599bd9084e4bc\", \"sandbox_linux_unittests\": \"fb1f1e4e7edf1da54bee607f365c4a31b9a60462\", \"service_manager_unittests\": \"05b0901a9442eaa89398c78f8c0598aa6d170592\", \"services_unittests\": \"8a10502207d742232ef6ecdeb14edae7d3004302\", \"shell_dialogs_unittests\": \"3a1df220f0e7a3ca5ef386aed008184563aa25dc\", \"shell_encryption_unittests\": \"300d99ad7e80be2550e61a33e081fb00bf495222\", \"skia_unittests\": \"4c047024c3f0808331e36c1527fa949daffcad1c\", \"snapshot_unittests\": \"6c0228c46e4b2848a26f9f7f3ed550fe79f56c3b\", \"sql_unittests\": \"638b3253fc23a3fe7585902e7f0a16f0c54ffc51\", \"storage_unittests\": \"3de021b87051435b9260610b30026825e02fff8f\", \"sync_integration_tests\": \"89e46e8934c720c86c237f97274166ce4f94e3cc\", \"traffic_annotation_auditor_unittests\": \"394c66e233d9853cce1428c59c8dfd457dcae3cc\", \"ui_base_unittests\": \"bdaaa24537f6679c52776252896230ae3e02620f\", \"ui_chromeos_unittests\": \"1a5fb6e7f9d250eae9c338c739f3a323c9e51dda\", \"ui_touch_selection_unittests\": \"d5f552685896377641226c1d2a608734b1568876\", \"unit_tests\": \"68cce0adb9256093e5ddd332e79a1cf8e2905abc\", \"url_unittests\": \"a6600d62065e10c5d4810a79d8e608bc5c5fe561\", \"usage_time_limit_unittests\": \"bd5992ce92df5bf9ea79268ba141ce6f91888311\", \"views_unittests\": \"f459c009ab596bbf35f3b99358e6594e7be8a7e7\", \"viz_unittests\": \"cba52642573a660ff8fec770d19e788f00b32884\", \"wayland_client_tests\": \"4975e27417a2a9ff752d1538ab75310a84ff9f5e\", \"wm_unittests\": \"94450d0334540bccab9a703421db4e02498e1b99\", \"wtf_unittests\": \"739718dd990ba88481d598332a29226391938138\", \"zlib_unittests\": \"c16662c5bccb880044cc7c386b75758d92a08d4c\"}}, \"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\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- UpdateRequiredScreenTest.TestUpdateOverMeteredNetwork\\n\\n- BetterUpdateScreenTest.TestBatteryWarningDuringUpdateStages\\n\\n- MediaRouterIntegrationBrowserTest.CloseOnError\\n\\n- BetterUpdateScreenTest.TestUpdateCompletedRebootNeeded\\n\\n- ...9 more failure(s) (13 total)...\\n\\n**ash_unittests** failed because of:\\n\\n- UpdateNotificationControllerTest.VisibilityAfterUpdateRequiringFactoryReset\\n\\n- AssistantAlarmTimerControllerTest.TimerNotificationHasExpectedTitleV2\\n\\n- PciePeripheralNotificationControllerTest.GuestNotificationTbtOnly\\n\\n- UpdateNotificationControllerTest.VisibilityAfterRollback\\n\\n- ...9 more failure(s) (13 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614263358409341",
"status": "COMPLETED",
"status_changed_ts": "1614263358652962",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22778",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4d1eacad252409cfc6ede47a2858262f17310ed2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d1eacad252409cfc6ede47a2858262f17310ed2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019746084585244864",
"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/8854316022611655600/+/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:51f267dc26c13610",
"user_agent:luci-scheduler"
],
"updated_ts": "1614269219541846",
"url": "https://ci.chromium.org/b/8854316022611655600",
"utcnow_ts": "1614269220413211"
}
}
[16:07:00.446] Invocation finished in 1h39m39.613115639s with status FAILED