[15:06:09.167] New invocation is queued and will start shortly
[15:06:10.475] Starting the invocation (attempt 1)
[15:06:10.511] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:06:10.512] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:06:10.512] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018475285639546272",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018475285639546272",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"gitiles_ref:refs/heads/master"
]
}
[15:06:11.021] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615475170594153",
"id": "8853045223477854768",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615475170906622",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22952",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018475285639546272",
"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/8853045223477854768/+/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": "1615475170906734",
"url": "https://ci.chromium.org/b/8853045223477854768",
"utcnow_ts": "1615475171014037"
}
}
[15:06:11.021] Task URL: https://ci.chromium.org/b/8853045223477854768
[15:06:11.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:2:0) after 1m0s
[15:07:11.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:2:0)
[15:07:11.027] Timer tick, asking Buildbucket for the build status
[15:07:11.109] Build 8853045223477854768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:07:11.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:3:0) after 1m0s
[15:08:04.820] Received PubSub notification, asking Buildbucket for the build status
[15:08:04.970] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:11.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:3:0)
[15:08:11.151] Timer tick, asking Buildbucket for the build status
[15:08:11.471] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:11.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:5:0) after 1m0s
[15:09:11.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:5:0)
[15:09:11.571] Timer tick, asking Buildbucket for the build status
[15:09:11.704] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:11.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:6:0) after 1m0s
[15:10:11.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:6:0)
[15:10:11.709] Timer tick, asking Buildbucket for the build status
[15:10:11.781] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:11.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:7:0) after 1m0s
[15:11:12.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:7:0)
[15:11:12.088] Timer tick, asking Buildbucket for the build status
[15:11:12.358] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:12.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:8:0) after 1m0s
[15:12:12.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:8:0)
[15:12:12.489] Timer tick, asking Buildbucket for the build status
[15:12:12.577] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:12.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:9:0) after 1m0s
[15:13:12.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:9:0)
[15:13:12.596] Timer tick, asking Buildbucket for the build status
[15:13:12.932] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:12.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:10:0) after 1m0s
[15:14:13.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:10:0)
[15:14:13.017] Timer tick, asking Buildbucket for the build status
[15:14:13.096] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:13.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:11:0) after 1m0s
[15:15:13.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:11:0)
[15:15:13.172] Timer tick, asking Buildbucket for the build status
[15:15:13.237] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:13.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:12:0) after 1m0s
[15:16:13.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:12:0)
[15:16:13.223] Timer tick, asking Buildbucket for the build status
[15:16:13.425] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:13.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:13:0) after 1m0s
[15:17:14.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:13:0)
[15:17:14.149] Timer tick, asking Buildbucket for the build status
[15:17:14.209] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:14.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:14:0) after 1m0s
[15:18:14.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:14:0)
[15:18:14.446] Timer tick, asking Buildbucket for the build status
[15:18:14.607] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:14.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:15:0) after 1m0s
[15:19:14.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:15:0)
[15:19:14.658] Timer tick, asking Buildbucket for the build status
[15:19:14.799] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:14.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:16:0) after 1m0s
[15:20:14.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:16:0)
[15:20:14.844] Timer tick, asking Buildbucket for the build status
[15:20:14.928] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:14.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:17:0) after 1m0s
[15:21:15.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:17:0)
[15:21:15.343] Timer tick, asking Buildbucket for the build status
[15:21:15.630] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:15.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:18:0) after 1m0s
[15:22:15.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:18:0)
[15:22:15.713] Timer tick, asking Buildbucket for the build status
[15:22:15.769] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:15.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:19:0) after 1m0s
[15:23:15.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:19:0)
[15:23:15.895] Timer tick, asking Buildbucket for the build status
[15:23:16.057] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:16.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:20:0) after 1m0s
[15:24:16.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:20:0)
[15:24:16.309] Timer tick, asking Buildbucket for the build status
[15:24:16.386] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:16.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:21:0) after 1m0s
[15:25:16.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:21:0)
[15:25:16.452] Timer tick, asking Buildbucket for the build status
[15:25:16.531] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:16.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:22:0) after 1m0s
[15:26:16.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:22:0)
[15:26:16.666] Timer tick, asking Buildbucket for the build status
[15:26:16.749] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:16.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:23:0) after 1m0s
[15:27:16.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:23:0)
[15:27:16.968] Timer tick, asking Buildbucket for the build status
[15:27:17.141] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:17.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:24:0) after 1m0s
[15:28:17.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:24:0)
[15:28:17.184] Timer tick, asking Buildbucket for the build status
[15:28:17.444] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:25:0) after 1m0s
[15:29:17.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:25:0)
[15:29:17.460] Timer tick, asking Buildbucket for the build status
[15:29:17.534] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:26:0) after 1m0s
[15:30:17.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:26:0)
[15:30:17.557] Timer tick, asking Buildbucket for the build status
[15:30:17.696] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:27:0) after 1m0s
[15:31:17.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:27:0)
[15:31:17.753] Timer tick, asking Buildbucket for the build status
[15:31:17.865] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:17.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:28:0) after 1m0s
[15:32:17.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:28:0)
[15:32:17.882] Timer tick, asking Buildbucket for the build status
[15:32:17.941] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:17.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:29:0) after 1m0s
[15:33:17.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:29:0)
[15:33:17.927] Timer tick, asking Buildbucket for the build status
[15:33:18.078] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:18.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:30:0) after 1m0s
[15:34:18.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:30:0)
[15:34:18.095] Timer tick, asking Buildbucket for the build status
[15:34:18.449] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:18.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:31:0) after 1m0s
[15:35:18.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:31:0)
[15:35:18.507] Timer tick, asking Buildbucket for the build status
[15:35:18.648] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:18.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:32:0) after 1m0s
[15:36:18.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:32:0)
[15:36:18.666] Timer tick, asking Buildbucket for the build status
[15:36:19.008] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:19.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:33:0) after 1m0s
[15:37:19.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:33:0)
[15:37:19.035] Timer tick, asking Buildbucket for the build status
[15:37:19.315] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:19.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:34:0) after 1m0s
[15:38:19.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:34:0)
[15:38:19.344] Timer tick, asking Buildbucket for the build status
[15:38:19.465] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:19.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:35:0) after 1m0s
[15:39:19.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:35:0)
[15:39:19.502] Timer tick, asking Buildbucket for the build status
[15:39:19.592] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:19.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:36:0) after 1m0s
[15:40:19.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:36:0)
[15:40:19.587] Timer tick, asking Buildbucket for the build status
[15:40:19.673] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:19.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:37:0) after 1m0s
[15:41:19.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:37:0)
[15:41:19.728] Timer tick, asking Buildbucket for the build status
[15:41:19.822] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:19.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:38:0) after 1m0s
[15:42:19.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:38:0)
[15:42:19.942] Timer tick, asking Buildbucket for the build status
[15:42:20.050] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:20.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:39:0) after 1m0s
[15:43:20.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:39:0)
[15:43:20.332] Timer tick, asking Buildbucket for the build status
[15:43:20.453] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:20.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:40:0) after 1m0s
[15:44:20.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:40:0)
[15:44:20.484] Timer tick, asking Buildbucket for the build status
[15:44:20.690] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:20.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:41:0) after 1m0s
[15:45:20.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:41:0)
[15:45:20.715] Timer tick, asking Buildbucket for the build status
[15:45:21.130] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:21.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:42:0) after 1m0s
[15:46:21.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:42:0)
[15:46:21.189] Timer tick, asking Buildbucket for the build status
[15:46:21.270] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:21.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:43:0) after 1m0s
[15:47:21.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:43:0)
[15:47:21.293] Timer tick, asking Buildbucket for the build status
[15:47:21.365] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:21.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:44:0) after 1m0s
[15:48:21.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:44:0)
[15:48:21.479] Timer tick, asking Buildbucket for the build status
[15:48:21.737] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:21.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:45:0) after 1m0s
[15:49:21.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:45:0)
[15:49:21.818] Timer tick, asking Buildbucket for the build status
[15:49:21.915] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:21.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:46:0) after 1m0s
[15:50:22.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:46:0)
[15:50:22.017] Timer tick, asking Buildbucket for the build status
[15:50:22.120] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:22.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:47:0) after 1m0s
[15:51:22.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:47:0)
[15:51:22.259] Timer tick, asking Buildbucket for the build status
[15:51:22.366] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:22.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:48:0) after 1m0s
[15:52:22.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:48:0)
[15:52:22.454] Timer tick, asking Buildbucket for the build status
[15:52:22.544] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:22.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:49:0) after 1m0s
[15:53:22.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:49:0)
[15:53:22.588] Timer tick, asking Buildbucket for the build status
[15:53:22.664] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:22.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:50:0) after 1m0s
[15:54:22.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:50:0)
[15:54:22.766] Timer tick, asking Buildbucket for the build status
[15:54:22.840] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:51:0) after 1m0s
[15:55:22.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:51:0)
[15:55:22.901] Timer tick, asking Buildbucket for the build status
[15:55:23.110] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:23.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:52:0) after 1m0s
[15:56:23.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:52:0)
[15:56:23.212] Timer tick, asking Buildbucket for the build status
[15:56:23.298] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:53:0) after 1m0s
[15:57:23.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:53:0)
[15:57:23.367] Timer tick, asking Buildbucket for the build status
[15:57:23.444] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:23.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:54:0) after 1m0s
[15:58:23.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:54:0)
[15:58:23.515] Timer tick, asking Buildbucket for the build status
[15:58:23.594] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:23.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:55:0) after 1m0s
[15:59:23.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:55:0)
[15:59:23.617] Timer tick, asking Buildbucket for the build status
[15:59:23.709] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:23.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:56:0) after 1m0s
[16:00:23.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:56:0)
[16:00:23.921] Timer tick, asking Buildbucket for the build status
[16:00:24.002] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:24.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:57:0) after 1m0s
[16:01:24.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:57:0)
[16:01:24.088] Timer tick, asking Buildbucket for the build status
[16:01:24.187] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:24.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:58:0) after 1m0s
[16:02:24.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:58:0)
[16:02:24.225] Timer tick, asking Buildbucket for the build status
[16:02:24.347] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:24.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:59:0) after 1m0s
[16:03:24.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:59:0)
[16:03:24.421] Timer tick, asking Buildbucket for the build status
[16:03:24.506] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:24.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:60:0) after 1m0s
[16:04:24.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:60:0)
[16:04:24.574] Timer tick, asking Buildbucket for the build status
[16:04:24.655] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:24.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:61:0) after 1m0s
[16:05:24.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:61:0)
[16:05:24.733] Timer tick, asking Buildbucket for the build status
[16:05:24.845] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:24.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:62:0) after 1m0s
[16:06:24.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:62:0)
[16:06:24.896] Timer tick, asking Buildbucket for the build status
[16:06:25.458] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:25.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:63:0) after 1m0s
[16:07:25.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:63:0)
[16:07:25.530] Timer tick, asking Buildbucket for the build status
[16:07:25.632] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:25.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:64:0) after 1m0s
[16:08:25.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:64:0)
[16:08:25.854] Timer tick, asking Buildbucket for the build status
[16:08:26.076] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:26.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:65:0) after 1m0s
[16:09:26.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:65:0)
[16:09:26.193] Timer tick, asking Buildbucket for the build status
[16:09:26.270] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:26.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:66:0) after 1m0s
[16:10:26.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:66:0)
[16:10:26.363] Timer tick, asking Buildbucket for the build status
[16:10:26.492] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:26.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:67:0) after 1m0s
[16:11:26.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:67:0)
[16:11:26.597] Timer tick, asking Buildbucket for the build status
[16:11:26.681] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:26.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:68:0) after 1m0s
[16:12:26.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:68:0)
[16:12:26.744] Timer tick, asking Buildbucket for the build status
[16:12:26.828] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:26.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:69:0) after 1m0s
[16:13:26.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:69:0)
[16:13:26.868] Timer tick, asking Buildbucket for the build status
[16:13:26.968] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:26.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:70:0) after 1m0s
[16:14:27.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:70:0)
[16:14:27.072] Timer tick, asking Buildbucket for the build status
[16:14:27.149] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:27.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:71:0) after 1m0s
[16:15:27.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:71:0)
[16:15:27.143] Timer tick, asking Buildbucket for the build status
[16:15:27.219] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:72:0) after 1m0s
[16:16:27.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:72:0)
[16:16:27.258] Timer tick, asking Buildbucket for the build status
[16:16:27.382] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:27.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:73:0) after 1m0s
[16:17:27.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:73:0)
[16:17:27.456] Timer tick, asking Buildbucket for the build status
[16:17:28.063] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:28.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:74:0) after 1m0s
[16:18:28.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:74:0)
[16:18:28.115] Timer tick, asking Buildbucket for the build status
[16:18:28.196] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:28.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:75:0) after 1m0s
[16:19:28.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:75:0)
[16:19:28.246] Timer tick, asking Buildbucket for the build status
[16:19:29.004] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:29.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:76:0) after 1m0s
[16:20:29.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:76:0)
[16:20:29.071] Timer tick, asking Buildbucket for the build status
[16:20:29.419] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:29.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:77:0) after 1m0s
[16:21:29.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:77:0)
[16:21:29.448] Timer tick, asking Buildbucket for the build status
[16:21:29.548] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:29.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:78:0) after 1m0s
[16:22:29.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:78:0)
[16:22:29.533] Timer tick, asking Buildbucket for the build status
[16:22:29.607] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:29.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:79:0) after 1m0s
[16:23:29.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:79:0)
[16:23:29.626] Timer tick, asking Buildbucket for the build status
[16:23:29.702] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:29.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:80:0) after 1m0s
[16:24:29.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:80:0)
[16:24:29.802] Timer tick, asking Buildbucket for the build status
[16:24:29.959] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:29.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:81:0) after 1m0s
[16:25:30.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:81:0)
[16:25:30.028] Timer tick, asking Buildbucket for the build status
[16:25:30.124] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:30.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:82:0) after 1m0s
[16:26:30.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:82:0)
[16:26:30.326] Timer tick, asking Buildbucket for the build status
[16:26:30.465] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:30.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:83:0) after 1m0s
[16:27:30.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:83:0)
[16:27:30.666] Timer tick, asking Buildbucket for the build status
[16:27:31.239] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:31.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:84:0) after 1m0s
[16:28:31.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:84:0)
[16:28:31.267] Timer tick, asking Buildbucket for the build status
[16:28:31.340] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:31.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:85:0) after 1m0s
[16:29:31.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:85:0)
[16:29:31.398] Timer tick, asking Buildbucket for the build status
[16:29:31.482] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:31.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:86:0) after 1m0s
[16:30:31.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:86:0)
[16:30:31.507] Timer tick, asking Buildbucket for the build status
[16:30:31.719] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:31.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:87:0) after 1m0s
[16:31:31.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:87:0)
[16:31:31.742] Timer tick, asking Buildbucket for the build status
[16:31:32.230] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:32.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:88:0) after 1m0s
[16:32:32.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:88:0)
[16:32:32.250] Timer tick, asking Buildbucket for the build status
[16:32:32.357] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:32.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:89:0) after 1m0s
[16:33:32.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:89:0)
[16:33:32.389] Timer tick, asking Buildbucket for the build status
[16:33:32.465] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:32.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:90:0) after 1m0s
[16:34:32.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:90:0)
[16:34:32.489] Timer tick, asking Buildbucket for the build status
[16:34:32.583] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:32.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:91:0) after 1m0s
[16:35:32.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:91:0)
[16:35:32.630] Timer tick, asking Buildbucket for the build status
[16:35:32.902] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:32.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:92:0) after 1m0s
[16:36:32.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:92:0)
[16:36:32.981] Timer tick, asking Buildbucket for the build status
[16:36:33.093] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:33.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:93:0) after 1m0s
[16:37:33.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:93:0)
[16:37:33.125] Timer tick, asking Buildbucket for the build status
[16:37:33.210] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:33.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:94:0) after 1m0s
[16:38:33.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:94:0)
[16:38:33.253] Timer tick, asking Buildbucket for the build status
[16:38:33.370] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:33.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:95:0) after 1m0s
[16:39:33.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:95:0)
[16:39:33.351] Timer tick, asking Buildbucket for the build status
[16:39:33.460] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:96:0) after 1m0s
[16:40:33.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:96:0)
[16:40:33.503] Timer tick, asking Buildbucket for the build status
[16:40:33.597] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:33.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:97:0) after 1m0s
[16:41:33.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:97:0)
[16:41:33.652] Timer tick, asking Buildbucket for the build status
[16:41:33.748] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:33.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:98:0) after 1m0s
[16:42:33.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:98:0)
[16:42:33.836] Timer tick, asking Buildbucket for the build status
[16:42:34.152] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:34.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:99:0) after 1m0s
[16:43:34.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:99:0)
[16:43:34.216] Timer tick, asking Buildbucket for the build status
[16:43:34.649] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:34.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:100:0) after 1m0s
[16:44:34.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:100:0)
[16:44:34.720] Timer tick, asking Buildbucket for the build status
[16:44:34.793] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:34.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:101:0) after 1m0s
[16:45:34.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:101:0)
[16:45:34.933] Timer tick, asking Buildbucket for the build status
[16:45:35.107] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:35.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:102:0) after 1m0s
[16:46:35.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:102:0)
[16:46:35.166] Timer tick, asking Buildbucket for the build status
[16:46:35.272] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:35.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:103:0) after 1m0s
[16:47:35.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:103:0)
[16:47:35.323] Timer tick, asking Buildbucket for the build status
[16:47:35.722] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:35.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:104:0) after 1m0s
[16:48:35.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:104:0)
[16:48:35.801] Timer tick, asking Buildbucket for the build status
[16:48:35.877] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:105:0) after 1m0s
[16:49:35.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:105:0)
[16:49:35.897] Timer tick, asking Buildbucket for the build status
[16:49:36.059] Build 8853045223477854768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:36.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018475285639546272:106:0) after 1m0s
[16:49:53.414] Received PubSub notification, asking Buildbucket for the build status
[16:49:53.676] Build 8853045223477854768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:49:53.676] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615481391699466",
"created_by": "project:chromium",
"created_ts": "1615475170594153",
"id": "8853045223477854768",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-44-4rk7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22952, \"got_angle_revision\": \"44b9579cf7b8d35cac13f9e2d70c57791ddfd0e2\", \"got_dawn_revision\": \"b458fc835925a222699fb62358c22047c70bd565\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\", \"got_revision_cp\": \"refs/heads/master@{#861974}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"9ce78ac45f91eddd6f118ed75e36e9081fc9ebc9\", \"got_v8_revision_cp\": \"refs/heads/9.1.99@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf\", \"swarm_hashes_refs/heads/master(at){#861974}_without_patch\": {\"absl_hardening_tests\": \"6f031a1dee0f12ace6a392b0422a14305013493c\", \"accessibility_unittests\": \"3a40f010fd24682ecb7e30357b91f9cb8295e89e\", \"angle_unittests\": \"7e86d8a997af34b0e262cfa19619f484ddc2082c\", \"app_list_unittests\": \"b8aa436b6b4efd34d4ae117e6fbafc1057222ed5\", \"app_shell_unittests\": \"51c68f6caeb02e39281eb1e113648c936d4f1a7a\", \"ash_unittests\": \"aaf38e7a5ed3cb0716118279c5aff04a4682fbe4\", \"aura_unittests\": \"ab7d25364e76ef4cd1bc066589564faf8c719c57\", \"base_unittests\": \"9863c0e9986f0e30709336c5e859bfeec1aabfd9\", \"base_util_unittests\": \"66e21ad39006faa4b97aee054f29e603311b4f3a\", \"blink_common_unittests\": \"81a572bd694f23eb0d667c5e91d8cad6ccea499a\", \"blink_fuzzer_unittests\": \"f97e544940fbee2246382193472e1f74764e3115\", \"blink_heap_unittests\": \"d18b47e87a854afebb5aaf5893c3c9df72c4019c\", \"blink_platform_unittests\": \"9ac64ca1ce5fc9bf230f754dca4b10f40a763f65\", \"blink_unittests\": \"070e96508f367e5a59cea49099b7e3f60dab932f\", \"boringssl_crypto_tests\": \"492bbba6b41c0ed25419f29ec0b9e59affbcba8f\", \"boringssl_ssl_tests\": \"edc530d50b2f2c93902957f5fef0b34b26a77442\", \"browser_tests\": \"3184340b45023babf3a08316f01e652914373d98\", \"capture_unittests\": \"a95e8abe9d01bc4e48405906c16f7546094930c9\", \"cast_unittests\": \"a196c38472c88ea2f761f8538af628f8090e4367\", \"cc_unittests\": \"d0d923771668703f934add92f9ea37e68e84bbc1\", \"chrome_app_unittests\": \"286cce522742904bcfe32b3d5bc0e6da5596f77d\", \"chromedriver_unittests\": \"2bea060eead4c2faa976c26a0189aac2bfc44096\", \"chromeos_components_unittests\": \"7f3ab658d0c50eadc14a5e532d3dfa97d2b6e846\", \"chromeos_unittests\": \"5410832222393b948ecd4565dfa3d703b1dec665\", \"color_unittests\": \"9214b963c780754138f089868579c1ee078dd850\", \"components_browsertests\": \"87c98ad4279f79e84d5d5583472142fbb05f7f57\", \"components_unittests\": \"8d51a97c507ddde6243fe0d6478c3640ed2fd820\", \"compositor_unittests\": \"8660d9f7e9053eb28c85346b40413bde210e07b0\", \"content_browsertests\": \"2144c2a77c6c04db769e72423eb38d279e10d9f2\", \"content_unittests\": \"0c99d5ef754b2aec253c427a8ff59fdb918b6021\", \"crashpad_tests\": \"c5b3bfb989796d2b23d080bd5d1e922312ca7c01\", \"crypto_unittests\": \"fefd16f3c299f3aacd0840ff14e9e907107304be\", \"dbus_unittests\": \"08a35897ccf3cf1c3a4ea6e131ebf8a2ce4b3cc0\", \"device_unittests\": \"ade1c953e6ac1aaba873b0e189d82b094a97e141\", \"display_unittests\": \"6fd5ecc2b777901767543a5c6e38f3deb4a5b2e9\", \"events_unittests\": \"b2af462c6a88e5a3cc5951bd80e06e4431bd9b4c\", \"exo_unittests\": \"d40c7d722f4a45cde5ac56ef7ddd46a355d4ca0f\", \"extensions_browsertests\": \"f9843ed831d49279144934c9f0ac5711654b8099\", \"extensions_unittests\": \"517e26fe9a9a0e8f225d9acdb10eb564e6bb900e\", \"filesystem_service_unittests\": \"55b452ae5d4cc847c3e6a9cd5aaee03694db11e8\", \"gcm_unit_tests\": \"0a18a2b3ffb14510d3ba3b725c5b756e0494b683\", \"gfx_unittests\": \"90be8631d034c3e951bcd038d574a65b93c296f4\", \"gin_unittests\": \"8d3797533ffdc4a00ab1e867aa7b842f66d02759\", \"gl_unittests_ozone\": \"bfb473d6ba75c1f5edecace5be83070d6af07c88\", \"google_apis_unittests\": \"5003a15ecffb5ef42bd387ae872ebc97e7b3070c\", \"gpu_unittests\": \"633a09aee454a064bb2d28d8d851599e4bc98c83\", \"gwp_asan_unittests\": \"6bd643b3d671ca609b54677d2ccb0c55e2cbd8be\", \"interactive_ui_tests\": \"2aae4190b2cbfc2943f18265d6761d4b1146e652\", \"ipc_tests\": \"f7eee1f6c0e083680f4e78062acdc2801074703d\", \"jingle_unittests\": \"b1a7ea67dd0ebe0ffc6ead8f28530ca6e2d7c5a5\", \"keyboard_unittests\": \"62c8730797e5032bf5c9fbb86c1291c13c68357a\", \"latency_unittests\": \"c183861198ca387c9957a3050139fb1d38168f0d\", \"libjingle_xmpp_unittests\": \"167ffbe25c6fc49163ed7ed32e147c1d09b3c73b\", \"liburlpattern_unittests\": \"45bef92e152e718904c458e82dc0f67c4140b4a8\", \"media_blink_unittests\": \"96bc32ab081c91c37697c422d912c7bd258a9c9b\", \"media_unittests\": \"8864d35fe75bbe660d97eca5a1bc8b1d0bd38afe\", \"message_center_unittests\": \"a2ad16ddc258eee6ecc8be5ea5bab64b7270a83c\", \"midi_unittests\": \"0fe82f841807e98dfbf3248bd86652bd268888a6\", \"mojo_core_unittests\": \"af16f71c3aaa066a7458cb3b3fd861dc7e68639b\", \"mojo_unittests\": \"f4bc74af1e28ae02fa64c95967c442b7eef62ce6\", \"nacl_helper_nonsfi_unittests\": \"7e1234124d5b6c355ca3b5eca5cc7ecc7cc79ea8\", \"nacl_loader_unittests\": \"007d0121cd5d49f0d6a52d5fd19231843753597c\", \"native_theme_unittests\": \"28f7e878b9e74170c67323c1f1a2ecc536c83c59\", \"net_unittests\": \"5eac70a0aa1edd8f4ec01a2e90e24b558234a449\", \"ozone_gl_unittests\": \"19b4de44adbbac4c6c4d2e44d331f9fdf71cf090\", \"ozone_unittests\": \"ebf934e5abe8f654487bdb18bd7682d8b5938b6c\", \"ozone_x11_unittests\": \"72a23c2037c058c63fa54d609fd9b37fd42dc5ff\", \"pdf_unittests\": \"ca89c4c438535363126b718c4d2c9e580db9190a\", \"perfetto_unittests\": \"e001c17f27e1f1977306ebe3f5147e40d2256785\", \"ppapi_unittests\": \"05ac3812ebe6a81f7b7cfa8650f70389211b64f6\", \"printing_unittests\": \"1afa1f3562d6b162610957295ecff833cac426cd\", \"remoting_unittests\": \"34ca2ae34faba95b04f57153a4c359541585d9a8\", \"sandbox_linux_unittests\": \"107f1af982a39545f021b68343a1852495eb003a\", \"service_manager_unittests\": \"8355333143449278073ba4ca62fb1270b30484ea\", \"services_unittests\": \"a167480e940c8041ec12795ffffb5b88ef3ab4a4\", \"shell_dialogs_unittests\": \"b0f0df0de10920c6874963d390dc7f6a74fa7da8\", \"shell_encryption_unittests\": \"3f5e6470b82a59d3502a90471a678753ebc63464\", \"skia_unittests\": \"ca3d55084e97f1cf03b17bdcd5cacbd3a08f1424\", \"snapshot_unittests\": \"647d81c9d95f5a4a3a9baca382536b73c278f8c1\", \"sql_unittests\": \"2089bd1ab2ec886551671d31462da151b1d01258\", \"storage_unittests\": \"29ff61d1ff6ceda1926e5c4c44e777247b79536b\", \"sync_integration_tests\": \"e5d94e40f249e67c80f36cc1b8aa80270973e1a2\", \"traffic_annotation_auditor_unittests\": \"bc509cbdfa193058ce457ebb86de22280b9bf984\", \"ui_base_unittests\": \"4d6152e8a81be3d80286034b4534ee8138b506a7\", \"ui_chromeos_unittests\": \"7d7787e1a5248d918ca087e73aab0bccb3743192\", \"ui_touch_selection_unittests\": \"aa385f5e7ac28dfdd6365b1da1fb7b10ec6b10c6\", \"unit_tests\": \"5a6ca29cf4bf53bb2990e0ae12db31ee1643682d\", \"url_unittests\": \"216affc023377af6503464eae05fd9911e6aa3d7\", \"usage_time_limit_unittests\": \"448f5ea1dfafdff13883a2a34a09180a14bc2f60\", \"views_unittests\": \"84b0d1a20efe45045f2d1e9577d12e46f277848a\", \"viz_unittests\": \"f9f2ec294d369e44b5c2430d978d0785384af2ed\", \"wayland_client_tests\": \"042ff7bf84a0ab7de9f7a37cf2090e30be9f1577\", \"wm_unittests\": \"a3f59d2691e361c5596950e0182c94436010c234\", \"wtf_unittests\": \"68725c6df7951b9e2f30e35163f00ce9ac179a5a\", \"zlib_unittests\": \"ca455e525a6eb508ee6d6318a8d3f80bf57b01d3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2bbcd421f4eb1113f3a23522b280d70ced47ef846cc2e40eada60721dff8d17e_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_8083860191ac1707bdac6310382d6ca226876fc8f88202f45d8d07453f74f5a4_v2\", \"builder_9ab1612fdb00db29a8d60b17a842600a8e29ecb37944be685dafbc3ddd01329a_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-44-4rk7\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615475283969524",
"status": "COMPLETED",
"status_changed_ts": "1615481392811977",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22952",
"builder:linux-chromeos-dbg",
"buildset:commit/git/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4de72c66df5d874e9d36ac5028d7f2e2d6e5abf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018475285639546272",
"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/8853045223477854768/+/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:523aa467d4697a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615481392830849",
"url": "https://ci.chromium.org/b/8853045223477854768",
"utcnow_ts": "1615481393664206"
}
}
[16:49:53.676] Invocation finished in 1h43m44.526883639s with status SUCCEEDED