[18:17:24.193] New invocation is queued and will start shortly
[18:17:25.265] Starting the invocation (attempt 1)
[18:17:25.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:17:25.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:17:25.339] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9080159787317568752",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ef19d59e601d35678abc909aaf830c35f4467982\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9080159787317568752",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/ef19d59e601d35678abc909aaf830c35f4467982",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef19d59e601d35678abc909aaf830c35f4467982",
"gitiles_ref:refs/heads/master"
]
}
[18:17:26.853] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1556648245627012",
"id": "8914729725151824992",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ef19d59e601d35678abc909aaf830c35f4467982\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556648246537110",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12144",
"builder:linux-chromeos-dbg",
"buildset:commit/git/ef19d59e601d35678abc909aaf830c35f4467982",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef19d59e601d35678abc909aaf830c35f4467982",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080159787317568752",
"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/8914729725151824992/+/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": "1556648246537274",
"url": "https://ci.chromium.org/b/8914729725151824992",
"utcnow_ts": "1556648246807741"
}
}
[18:17:26.853] Task URL: https://ci.chromium.org/b/8914729725151824992
[18:17:26.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:2:0) after 1m0s
[18:18:26.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:2:0)
[18:18:26.874] Timer tick, asking Buildbucket for the build status
[18:18:27.565] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:27.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:3:0) after 1m0s
[18:19:27.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:3:0)
[18:19:27.586] Timer tick, asking Buildbucket for the build status
[18:19:28.181] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:28.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:4:0) after 1m0s
[18:20:28.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:4:0)
[18:20:28.213] Timer tick, asking Buildbucket for the build status
[18:20:29.097] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:29.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:5:0) after 1m0s
[18:21:29.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:5:0)
[18:21:29.074] Timer tick, asking Buildbucket for the build status
[18:21:29.199] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:29.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:6:0) after 1m0s
[18:22:29.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:6:0)
[18:22:29.219] Timer tick, asking Buildbucket for the build status
[18:22:30.009] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:30.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:7:0) after 1m0s
[18:23:29.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:7:0)
[18:23:29.986] Timer tick, asking Buildbucket for the build status
[18:23:30.115] Build 8914729725151824992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:30.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:8:0) after 1m0s
[18:23:55.437] Received PubSub notification, asking Buildbucket for the build status
[18:23:56.091] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:30.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:8:0)
[18:24:30.114] Timer tick, asking Buildbucket for the build status
[18:24:30.492] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:30.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:10:0) after 1m0s
[18:25:30.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:10:0)
[18:25:30.533] Timer tick, asking Buildbucket for the build status
[18:25:30.630] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:30.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:11:0) after 1m0s
[18:26:30.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:11:0)
[18:26:30.637] Timer tick, asking Buildbucket for the build status
[18:26:30.855] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:30.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:12:0) after 1m0s
[18:27:30.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:12:0)
[18:27:30.922] Timer tick, asking Buildbucket for the build status
[18:27:31.058] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:31.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:13:0) after 1m0s
[18:28:31.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:13:0)
[18:28:31.077] Timer tick, asking Buildbucket for the build status
[18:28:31.246] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:31.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:14:0) after 1m0s
[18:29:31.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:14:0)
[18:29:31.261] Timer tick, asking Buildbucket for the build status
[18:29:31.436] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:31.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:15:0) after 1m0s
[18:30:31.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:15:0)
[18:30:31.443] Timer tick, asking Buildbucket for the build status
[18:30:31.735] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:31.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:16:0) after 1m0s
[18:31:31.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:16:0)
[18:31:31.752] Timer tick, asking Buildbucket for the build status
[18:31:31.979] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:31.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:17:0) after 1m0s
[18:32:32.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:17:0)
[18:32:32.005] Timer tick, asking Buildbucket for the build status
[18:32:32.129] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:32.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:18:0) after 1m0s
[18:33:32.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:18:0)
[18:33:32.213] Timer tick, asking Buildbucket for the build status
[18:33:32.762] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:32.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:19:0) after 1m0s
[18:34:32.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:19:0)
[18:34:32.779] Timer tick, asking Buildbucket for the build status
[18:34:33.287] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:33.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:20:0) after 1m0s
[18:35:33.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:20:0)
[18:35:33.300] Timer tick, asking Buildbucket for the build status
[18:35:33.760] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:33.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:21:0) after 1m0s
[18:36:33.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:21:0)
[18:36:33.778] Timer tick, asking Buildbucket for the build status
[18:36:34.093] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:34.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:22:0) after 1m0s
[18:37:34.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:22:0)
[18:37:34.088] Timer tick, asking Buildbucket for the build status
[18:37:34.217] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:34.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:23:0) after 1m0s
[18:38:34.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:23:0)
[18:38:34.237] Timer tick, asking Buildbucket for the build status
[18:38:34.361] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:34.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:24:0) after 1m0s
[18:39:34.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:24:0)
[18:39:34.380] Timer tick, asking Buildbucket for the build status
[18:39:34.661] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:34.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:25:0) after 1m0s
[18:40:34.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:25:0)
[18:40:34.681] Timer tick, asking Buildbucket for the build status
[18:40:34.791] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:34.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:26:0) after 1m0s
[18:41:34.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:26:0)
[18:41:34.772] Timer tick, asking Buildbucket for the build status
[18:41:34.958] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:34.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:27:0) after 1m0s
[18:42:34.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:27:0)
[18:42:34.955] Timer tick, asking Buildbucket for the build status
[18:42:35.222] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:35.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:28:0) after 1m0s
[18:43:35.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:28:0)
[18:43:35.323] Timer tick, asking Buildbucket for the build status
[18:43:35.439] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:35.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:29:0) after 1m0s
[18:44:35.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:29:0)
[18:44:35.452] Timer tick, asking Buildbucket for the build status
[18:44:36.201] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:36.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:30:0) after 1m0s
[18:45:36.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:30:0)
[18:45:36.212] Timer tick, asking Buildbucket for the build status
[18:45:36.793] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:36.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:31:0) after 1m0s
[18:46:36.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:31:0)
[18:46:36.795] Timer tick, asking Buildbucket for the build status
[18:46:37.096] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:37.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:32:0) after 1m0s
[18:47:37.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:32:0)
[18:47:37.106] Timer tick, asking Buildbucket for the build status
[18:47:37.539] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:37.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:33:0) after 1m0s
[18:48:37.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:33:0)
[18:48:37.565] Timer tick, asking Buildbucket for the build status
[18:48:37.675] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:37.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:34:0) after 1m0s
[18:49:37.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:34:0)
[18:49:37.696] Timer tick, asking Buildbucket for the build status
[18:49:38.211] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:38.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:35:0) after 1m0s
[18:50:38.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:35:0)
[18:50:38.209] Timer tick, asking Buildbucket for the build status
[18:50:38.310] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:38.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:36:0) after 1m0s
[18:51:38.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:36:0)
[18:51:38.310] Timer tick, asking Buildbucket for the build status
[18:51:38.406] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:38.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:37:0) after 1m0s
[18:52:38.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:37:0)
[18:52:38.376] Timer tick, asking Buildbucket for the build status
[18:52:38.449] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:38.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:38:0) after 1m0s
[18:53:38.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:38:0)
[18:53:38.491] Timer tick, asking Buildbucket for the build status
[18:53:38.634] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:38.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:39:0) after 1m0s
[18:54:38.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:39:0)
[18:54:38.688] Timer tick, asking Buildbucket for the build status
[18:54:39.160] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:39.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:40:0) after 1m0s
[18:55:39.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:40:0)
[18:55:39.184] Timer tick, asking Buildbucket for the build status
[18:55:39.611] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:39.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:41:0) after 1m0s
[18:56:39.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:41:0)
[18:56:39.600] Timer tick, asking Buildbucket for the build status
[18:56:40.603] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:40.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:42:0) after 1m0s
[18:57:40.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:42:0)
[18:57:40.582] Timer tick, asking Buildbucket for the build status
[18:57:40.763] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:40.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:43:0) after 1m0s
[18:58:40.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:43:0)
[18:58:40.786] Timer tick, asking Buildbucket for the build status
[18:58:40.941] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:40.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:44:0) after 1m0s
[18:59:40.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:44:0)
[18:59:40.959] Timer tick, asking Buildbucket for the build status
[18:59:41.122] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:45:0) after 1m0s
[19:00:41.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:45:0)
[19:00:41.155] Timer tick, asking Buildbucket for the build status
[19:00:43.198] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:43.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:46:0) after 1m0s
[19:01:43.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:46:0)
[19:01:43.200] Timer tick, asking Buildbucket for the build status
[19:01:43.600] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:43.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:47:0) after 1m0s
[19:02:43.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:47:0)
[19:02:43.638] Timer tick, asking Buildbucket for the build status
[19:02:43.997] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:43.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:48:0) after 1m0s
[19:03:44.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:48:0)
[19:03:44.042] Timer tick, asking Buildbucket for the build status
[19:03:44.628] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:44.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:49:0) after 1m0s
[19:04:44.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:49:0)
[19:04:44.636] Timer tick, asking Buildbucket for the build status
[19:04:44.794] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:44.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:50:0) after 1m0s
[19:05:44.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:50:0)
[19:05:44.900] Timer tick, asking Buildbucket for the build status
[19:05:45.030] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:45.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:51:0) after 1m0s
[19:06:45.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:51:0)
[19:06:45.038] Timer tick, asking Buildbucket for the build status
[19:06:45.688] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:45.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:52:0) after 1m0s
[19:07:45.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:52:0)
[19:07:45.695] Timer tick, asking Buildbucket for the build status
[19:07:46.486] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:46.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:53:0) after 1m0s
[19:08:46.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:53:0)
[19:08:46.508] Timer tick, asking Buildbucket for the build status
[19:08:46.701] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:46.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:54:0) after 1m0s
[19:09:46.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:54:0)
[19:09:46.678] Timer tick, asking Buildbucket for the build status
[19:09:46.868] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:46.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:55:0) after 1m0s
[19:10:46.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:55:0)
[19:10:46.867] Timer tick, asking Buildbucket for the build status
[19:10:46.995] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:46.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:56:0) after 1m0s
[19:11:47.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:56:0)
[19:11:47.002] Timer tick, asking Buildbucket for the build status
[19:11:47.720] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:47.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:57:0) after 1m0s
[19:12:47.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:57:0)
[19:12:47.764] Timer tick, asking Buildbucket for the build status
[19:12:48.086] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:48.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:58:0) after 1m0s
[19:13:48.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:58:0)
[19:13:48.102] Timer tick, asking Buildbucket for the build status
[19:13:48.222] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:48.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:59:0) after 1m0s
[19:14:48.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:59:0)
[19:14:48.222] Timer tick, asking Buildbucket for the build status
[19:14:48.561] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:48.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:60:0) after 1m0s
[19:15:48.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:60:0)
[19:15:48.586] Timer tick, asking Buildbucket for the build status
[19:15:48.938] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:48.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:61:0) after 1m0s
[19:16:48.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:61:0)
[19:16:48.924] Timer tick, asking Buildbucket for the build status
[19:16:49.768] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:62:0) after 1m0s
[19:17:49.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:62:0)
[19:17:49.952] Timer tick, asking Buildbucket for the build status
[19:17:50.225] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:50.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:63:0) after 1m0s
[19:18:50.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:63:0)
[19:18:50.295] Timer tick, asking Buildbucket for the build status
[19:18:50.776] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:50.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:64:0) after 1m0s
[19:19:50.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:64:0)
[19:19:50.801] Timer tick, asking Buildbucket for the build status
[19:19:50.905] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:50.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:65:0) after 1m0s
[19:20:50.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:65:0)
[19:20:50.942] Timer tick, asking Buildbucket for the build status
[19:20:51.120] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:51.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:66:0) after 1m0s
[19:21:51.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:66:0)
[19:21:51.188] Timer tick, asking Buildbucket for the build status
[19:21:51.585] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:51.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:67:0) after 1m0s
[19:22:51.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:67:0)
[19:22:51.648] Timer tick, asking Buildbucket for the build status
[19:22:51.890] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:51.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:68:0) after 1m0s
[19:23:51.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:68:0)
[19:23:51.911] Timer tick, asking Buildbucket for the build status
[19:23:52.568] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:52.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:69:0) after 1m0s
[19:24:52.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:69:0)
[19:24:52.585] Timer tick, asking Buildbucket for the build status
[19:24:53.096] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:53.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:70:0) after 1m0s
[19:25:53.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:70:0)
[19:25:53.088] Timer tick, asking Buildbucket for the build status
[19:25:53.262] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:53.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:71:0) after 1m0s
[19:26:53.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:71:0)
[19:26:53.326] Timer tick, asking Buildbucket for the build status
[19:26:53.526] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:53.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:72:0) after 1m0s
[19:27:53.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:72:0)
[19:27:53.539] Timer tick, asking Buildbucket for the build status
[19:27:54.363] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:73:0) after 1m0s
[19:28:54.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:73:0)
[19:28:54.386] Timer tick, asking Buildbucket for the build status
[19:28:55.090] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:55.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:74:0) after 1m0s
[19:29:55.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:74:0)
[19:29:55.128] Timer tick, asking Buildbucket for the build status
[19:29:55.562] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:55.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:75:0) after 1m0s
[19:30:55.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:75:0)
[19:30:55.589] Timer tick, asking Buildbucket for the build status
[19:30:56.068] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:56.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:76:0) after 1m0s
[19:31:56.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:76:0)
[19:31:56.057] Timer tick, asking Buildbucket for the build status
[19:31:56.302] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:56.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:77:0) after 1m0s
[19:32:56.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:77:0)
[19:32:56.309] Timer tick, asking Buildbucket for the build status
[19:32:56.495] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:56.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:78:0) after 1m0s
[19:33:56.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:78:0)
[19:33:56.506] Timer tick, asking Buildbucket for the build status
[19:33:56.704] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:56.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:79:0) after 1m0s
[19:34:56.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:79:0)
[19:34:56.713] Timer tick, asking Buildbucket for the build status
[19:34:56.985] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:56.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:80:0) after 1m0s
[19:35:57.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:80:0)
[19:35:57.013] Timer tick, asking Buildbucket for the build status
[19:35:57.196] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:57.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:81:0) after 1m0s
[19:36:57.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:81:0)
[19:36:57.221] Timer tick, asking Buildbucket for the build status
[19:36:57.980] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:82:0) after 1m0s
[19:37:58.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:82:0)
[19:37:58.000] Timer tick, asking Buildbucket for the build status
[19:37:58.553] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:58.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:83:0) after 1m0s
[19:38:58.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:83:0)
[19:38:58.596] Timer tick, asking Buildbucket for the build status
[19:38:59.299] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:59.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:84:0) after 1m0s
[19:39:59.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:84:0)
[19:39:59.357] Timer tick, asking Buildbucket for the build status
[19:39:59.503] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:59.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:85:0) after 1m0s
[19:40:59.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:85:0)
[19:40:59.504] Timer tick, asking Buildbucket for the build status
[19:40:59.753] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:59.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:86:0) after 1m0s
[19:41:59.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:86:0)
[19:41:59.798] Timer tick, asking Buildbucket for the build status
[19:42:00.188] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:00.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:87:0) after 1m0s
[19:43:00.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:87:0)
[19:43:00.212] Timer tick, asking Buildbucket for the build status
[19:43:00.366] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:00.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:88:0) after 1m0s
[19:44:00.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:88:0)
[19:44:00.397] Timer tick, asking Buildbucket for the build status
[19:44:00.891] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:00.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:89:0) after 1m0s
[19:45:00.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:89:0)
[19:45:00.882] Timer tick, asking Buildbucket for the build status
[19:45:01.287] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:01.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:90:0) after 1m0s
[19:46:01.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:90:0)
[19:46:01.316] Timer tick, asking Buildbucket for the build status
[19:46:01.886] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:01.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:91:0) after 1m0s
[19:47:01.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:91:0)
[19:47:01.909] Timer tick, asking Buildbucket for the build status
[19:47:02.508] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:02.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:92:0) after 1m0s
[19:48:02.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:92:0)
[19:48:02.534] Timer tick, asking Buildbucket for the build status
[19:48:02.885] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:02.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:93:0) after 1m0s
[19:49:02.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:93:0)
[19:49:02.904] Timer tick, asking Buildbucket for the build status
[19:49:03.660] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:03.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:94:0) after 1m0s
[19:50:03.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:94:0)
[19:50:03.681] Timer tick, asking Buildbucket for the build status
[19:50:03.892] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:03.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:95:0) after 1m0s
[19:51:03.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:95:0)
[19:51:03.931] Timer tick, asking Buildbucket for the build status
[19:51:05.184] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:05.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:96:0) after 1m0s
[19:52:05.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:96:0)
[19:52:05.219] Timer tick, asking Buildbucket for the build status
[19:52:05.592] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:05.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:97:0) after 1m0s
[19:53:05.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:97:0)
[19:53:05.618] Timer tick, asking Buildbucket for the build status
[19:53:05.881] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:05.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:98:0) after 1m0s
[19:54:05.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:98:0)
[19:54:05.902] Timer tick, asking Buildbucket for the build status
[19:54:06.024] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:06.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:99:0) after 1m0s
[19:55:06.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:99:0)
[19:55:06.017] Timer tick, asking Buildbucket for the build status
[19:55:06.175] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:06.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:100:0) after 1m0s
[19:56:06.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:100:0)
[19:56:06.159] Timer tick, asking Buildbucket for the build status
[19:56:06.446] Build 8914729725151824992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:06.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9080159787317568752:101:0) after 1m0s
[19:56:49.850] Received PubSub notification, asking Buildbucket for the build status
[19:56:50.100] Build 8914729725151824992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:56:50.101] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1556654208791950",
"created_by": "project:chromium",
"created_ts": "1556648245627012",
"id": "8914729725151824992",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ef19d59e601d35678abc909aaf830c35f4467982\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 12144, \"got_angle_revision\": \"1ad7a07212b28ef9b74b24f4d76d06641cafaec3\", \"got_dawn_revision\": \"11b703904ab61b08f08aae8e36cdb726826e6487\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"ef19d59e601d35678abc909aaf830c35f4467982\", \"got_revision_cp\": \"refs/heads/master@{#655281}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"b5ce76ecb22a8a0aad5d2eee9e011092a4a965e2\", \"got_v8_revision_cp\": \"refs/heads/7.6.70@{#1}\", \"got_webrtc_revision\": \"bc6a4d094fe3f790af7f26061f1b988a9d6464d4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27808}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ef19d59e601d35678abc909aaf830c35f4467982\", \"swarm_hashes\": {\"accessibility_unittests\": \"e19b83997c18255d2fa213096e671c77b0521cef\", \"angle_unittests\": \"451f6b7af38900784ff6d49d4e681d41583f1366\", \"app_list_unittests\": \"232f91f1438a0212b4e09e82753ce1f9acf18cd8\", \"app_shell_unittests\": \"2e26ca66885f86f8627a31cc53b9061eb3085f0c\", \"ash_unittests\": \"1b8713cfd6174b84a4b828d9e777876754980603\", \"aura_unittests\": \"c4249e7ed56b1d0880e4a8b509f2cee047e1991c\", \"base_unittests\": \"df2a394d3eff97f2cc3e450f3197a40957a8c198\", \"blink_common_unittests\": \"080e5ccb215b4b2c68c9ee568fff0303066c2f8b\", \"blink_fuzzer_unittests\": \"941828439ed529a36606923e0badc35f315a0571\", \"blink_heap_unittests\": \"68aa5c1e41ec717dbdc71429e29153a04b99293f\", \"blink_platform_unittests\": \"6dfe00d2c7cb0ce8cad172924de12b5a7aa48a8f\", \"blink_unittests\": \"060c640a34c875003f59eae8817514f65543576a\", \"boringssl_crypto_tests\": \"6cd1bd7314cd918fe8d2316b317906221ee72989\", \"boringssl_ssl_tests\": \"d578215c08219f1a2e410dae13acd111bcfab0b7\", \"browser_tests\": \"6fff443e52d4a5d6234b69a307bfbb866671ce45\", \"cacheinvalidation_unittests\": \"3d26cbeb21341f8d7a2c7d33312678c8b27e7fed\", \"capture_unittests\": \"3143435054754945ff41b7af4a66e7209fe39013\", \"cast_unittests\": \"05f2e1d4c66963ce9e39cd38f5d9258547a596d1\", \"cc_unittests\": \"181e05d09f336bc5b3079a23d44b8b3efca6a36e\", \"chrome_app_unittests\": \"d6b023510d23dd53b3eeb92f0d02b47767a87188\", \"chromedriver_unittests\": \"b0cd86ab6f0a18b4ace5c81f74fdf142eb08d16f\", \"chromeos_components_unittests\": \"0d5960f1a2e8ae4ce139048c99572f6e955a04de\", \"chromeos_unittests\": \"d1006ddec7bb9b90eda39c7b5584a59ea0dc7a42\", \"components_browsertests\": \"97412735cf75cfc1c8c521eb1e7ddf75ed033761\", \"components_unittests\": \"fb08881114f826191fd400548b474b7208806d05\", \"compositor_unittests\": \"29683160cbcaa8d65ad365971dfeea0a8601e4d9\", \"content_browsertests\": \"212573b3d05367084cc7cb212da82723629e327e\", \"content_unittests\": \"64a5d8f95f6bf81b6712a720787d9d2d5767f952\", \"crypto_unittests\": \"e67bcd44f442690ed638395a0b9c0fac00fef096\", \"dbus_unittests\": \"52cc7d85fe7327096237a5554c1a937927bc4983\", \"device_unittests\": \"808f2a2c1ba41ac10a1ef5e551fc5885d4495e58\", \"display_unittests\": \"31bb00390cd81d3ff9340aecacea4f22dffd14d9\", \"events_unittests\": \"2bf330e1aa926f67506aad1fe14075b96f555766\", \"exo_unittests\": \"c8b365b7e38865f05fce6b40286f4888c4cc6805\", \"extensions_browsertests\": \"29f7a58accfe380adc40f1915a075e17fe735fa7\", \"extensions_unittests\": \"806999182513aee3fd367b84a38646dd945d0bf1\", \"filesystem_service_unittests\": \"de280cc6e33fb6e7d1db92e1d58655b0fc66d213\", \"gcm_unit_tests\": \"0cf9305fc53b9454f371d49bee197021971141b7\", \"gfx_unittests\": \"9c04d8c18c841795ad55ee70ec59bd45378210cb\", \"gin_unittests\": \"54ff2e9d355882f16bdd1599985bf41e2f9cad07\", \"gl_unittests_ozone\": \"07308afdaff3ef69285592211853701df88aef4f\", \"google_apis_unittests\": \"cfc8155ec177197dae50be27bde1b53b210ce1d5\", \"gpu_unittests\": \"bb79928323d2b4b1ed683714a0f5cc6c26bccb42\", \"interactive_ui_tests\": \"7f27bccf9b329b9bddad6f373ba0bef1aac9466a\", \"ipc_tests\": \"809c765a7ea64e0a36c9f341bbc144745f37d2c4\", \"jingle_unittests\": \"5f92b767e4cd3a6adef89fc255ea0a9a6e8cdf8d\", \"keyboard_unittests\": \"f7997d9e0d0b6a6f19ebd7ed3fb5b32e8e3a92cf\", \"latency_unittests\": \"d4154ed4775d44d2caca93b3be600b26c6782ab0\", \"leveldb_service_unittests\": \"fbbded6cf231708ced99ffe3c57e76be61038095\", \"libjingle_xmpp_unittests\": \"2ea31fa4afdba80e7c34893fb533e6033e2aad77\", \"media_blink_unittests\": \"1f04a77e6b13229a2f543c1b2c27f29ead5281ad\", \"media_service_unittests\": \"d550a94178ea4512c95ea308a2d68faf0810af37\", \"media_unittests\": \"68393f2b1867c4b9042cd59cf337dcdf22801937\", \"message_center_unittests\": \"54acec1db8b88bb10b6bd108e99c0d644a4e94e9\", \"midi_unittests\": \"6857b61229f87f620dff79403b2060d8205500ca\", \"mojo_core_unittests\": \"ee3856da37d20590d6f65a4481ee5d70c7077d49\", \"mojo_unittests\": \"6916ccfa4ad8fb02b4e7fdc6a4e3fa3f7dcd536f\", \"nacl_helper_nonsfi_unittests\": \"28ed71d21278876205cbca17a2f21e3385f7f419\", \"nacl_loader_unittests\": \"35e96f0e8ecf3de34cc45a7d7a835e6ae6cdc3e6\", \"native_theme_unittests\": \"4f0b790bef85fa3f260edf7478e4f640d42ec099\", \"net_unittests\": \"9dcbd81be7ab4af55dd6628d0f59c8e75e7b71c9\", \"ozone_gl_unittests\": \"bdda822f8145be89bb23c5811324db3026c7a2fb\", \"ozone_unittests\": \"fc0f6121540be485337397989ce49ae4f957ea7b\", \"ozone_x11_unittests\": \"840d7b899189e5125b4fee04efa458a4c475c4e9\", \"pdf_unittests\": \"e48fdd3dd97344f1cc3671a9809dac921d1eb036\", \"perfetto_unittests\": \"43401de134982bc4662ce0cfb734c48b5d9261d6\", \"ppapi_unittests\": \"764c4f97be28d407c62ba3e1fe3498e3aa80f888\", \"printing_unittests\": \"416e37dc8979587cb5ab1ec842d4625cdd7764c1\", \"remoting_unittests\": \"d37706abc307417b1ebb53700e95bfb901f03611\", \"sandbox_linux_unittests\": \"e15ee6ab447ed95044370d6f6ec50dba05f7f02b\", \"service_manager_unittests\": \"525d07cbe1b476f91706ff3392a025ad9e0e8039\", \"services_unittests\": \"149c5f813f73c1ceff122ba16cbdff2b0ffeaef0\", \"shell_dialogs_unittests\": \"9c67fac62960710f90266e49271192f617442ad5\", \"skia_unittests\": \"02d677af09d9ca674707391eead61a01e81846f5\", \"snapshot_unittests\": \"469a58ead29ff4219deaa9de613f0b314aa69017\", \"sql_unittests\": \"32a96b95d815ea1f01776ac0dd450ce04f0f35e0\", \"storage_unittests\": \"f0b9a45fa92756ed3d44bb908c6a0437f864ecc1\", \"sync_integration_tests\": \"1cf894eaff90d830a3e3c543f821fd384888ebd0\", \"traffic_annotation_auditor_unittests\": \"ce44354092a2ffb21cd823bf8d4b972ce0a9658c\", \"ui_base_unittests\": \"13b8e0c7be28ea8ab138d5faf583e4a52820a8f5\", \"ui_chromeos_unittests\": \"9d14f8cf53407fc64c1da8464f1e9c556327d04b\", \"ui_touch_selection_unittests\": \"fb14b7461d059c83498555c4e5c4c75b5476ce5e\", \"unit_tests\": \"ea295e01fb1c9d32eb68375bcf243cf4e5303c39\", \"url_unittests\": \"1c471fdea2f5000b68329acb65ded6f84070923b\", \"usage_time_limit_unittests\": \"96ae8dde3d57dbabf9e16b68dec0a9a1db2cbd29\", \"views_unittests\": \"196189901a5a8342d96b09f6bfbd466e696029f9\", \"viz_unittests\": \"e5e8bd5ef2da76324590f99d129bd4727631fa7b\", \"wm_unittests\": \"53f6993f3c71b21253d2490950c33850be108ae5\", \"wtf_unittests\": \"ece976d61a40ccdf157e55080c4478c00828e477\"}, \"swarm_hashes_refs/heads/master(at){#655281}_without_patch\": {\"accessibility_unittests\": \"e19b83997c18255d2fa213096e671c77b0521cef\", \"angle_unittests\": \"451f6b7af38900784ff6d49d4e681d41583f1366\", \"app_list_unittests\": \"232f91f1438a0212b4e09e82753ce1f9acf18cd8\", \"app_shell_unittests\": \"2e26ca66885f86f8627a31cc53b9061eb3085f0c\", \"ash_unittests\": \"1b8713cfd6174b84a4b828d9e777876754980603\", \"aura_unittests\": \"c4249e7ed56b1d0880e4a8b509f2cee047e1991c\", \"base_unittests\": \"df2a394d3eff97f2cc3e450f3197a40957a8c198\", \"blink_common_unittests\": \"080e5ccb215b4b2c68c9ee568fff0303066c2f8b\", \"blink_fuzzer_unittests\": \"941828439ed529a36606923e0badc35f315a0571\", \"blink_heap_unittests\": \"68aa5c1e41ec717dbdc71429e29153a04b99293f\", \"blink_platform_unittests\": \"6dfe00d2c7cb0ce8cad172924de12b5a7aa48a8f\", \"blink_unittests\": \"060c640a34c875003f59eae8817514f65543576a\", \"boringssl_crypto_tests\": \"6cd1bd7314cd918fe8d2316b317906221ee72989\", \"boringssl_ssl_tests\": \"d578215c08219f1a2e410dae13acd111bcfab0b7\", \"browser_tests\": \"6fff443e52d4a5d6234b69a307bfbb866671ce45\", \"cacheinvalidation_unittests\": \"3d26cbeb21341f8d7a2c7d33312678c8b27e7fed\", \"capture_unittests\": \"3143435054754945ff41b7af4a66e7209fe39013\", \"cast_unittests\": \"05f2e1d4c66963ce9e39cd38f5d9258547a596d1\", \"cc_unittests\": \"181e05d09f336bc5b3079a23d44b8b3efca6a36e\", \"chrome_app_unittests\": \"d6b023510d23dd53b3eeb92f0d02b47767a87188\", \"chromedriver_unittests\": \"b0cd86ab6f0a18b4ace5c81f74fdf142eb08d16f\", \"chromeos_components_unittests\": \"0d5960f1a2e8ae4ce139048c99572f6e955a04de\", \"chromeos_unittests\": \"d1006ddec7bb9b90eda39c7b5584a59ea0dc7a42\", \"components_browsertests\": \"97412735cf75cfc1c8c521eb1e7ddf75ed033761\", \"components_unittests\": \"fb08881114f826191fd400548b474b7208806d05\", \"compositor_unittests\": \"29683160cbcaa8d65ad365971dfeea0a8601e4d9\", \"content_browsertests\": \"212573b3d05367084cc7cb212da82723629e327e\", \"content_unittests\": \"64a5d8f95f6bf81b6712a720787d9d2d5767f952\", \"crypto_unittests\": \"e67bcd44f442690ed638395a0b9c0fac00fef096\", \"dbus_unittests\": \"52cc7d85fe7327096237a5554c1a937927bc4983\", \"device_unittests\": \"808f2a2c1ba41ac10a1ef5e551fc5885d4495e58\", \"display_unittests\": \"31bb00390cd81d3ff9340aecacea4f22dffd14d9\", \"events_unittests\": \"2bf330e1aa926f67506aad1fe14075b96f555766\", \"exo_unittests\": \"c8b365b7e38865f05fce6b40286f4888c4cc6805\", \"extensions_browsertests\": \"29f7a58accfe380adc40f1915a075e17fe735fa7\", \"extensions_unittests\": \"806999182513aee3fd367b84a38646dd945d0bf1\", \"filesystem_service_unittests\": \"de280cc6e33fb6e7d1db92e1d58655b0fc66d213\", \"gcm_unit_tests\": \"0cf9305fc53b9454f371d49bee197021971141b7\", \"gfx_unittests\": \"9c04d8c18c841795ad55ee70ec59bd45378210cb\", \"gin_unittests\": \"54ff2e9d355882f16bdd1599985bf41e2f9cad07\", \"gl_unittests_ozone\": \"07308afdaff3ef69285592211853701df88aef4f\", \"google_apis_unittests\": \"cfc8155ec177197dae50be27bde1b53b210ce1d5\", \"gpu_unittests\": \"bb79928323d2b4b1ed683714a0f5cc6c26bccb42\", \"interactive_ui_tests\": \"7f27bccf9b329b9bddad6f373ba0bef1aac9466a\", \"ipc_tests\": \"809c765a7ea64e0a36c9f341bbc144745f37d2c4\", \"jingle_unittests\": \"5f92b767e4cd3a6adef89fc255ea0a9a6e8cdf8d\", \"keyboard_unittests\": \"f7997d9e0d0b6a6f19ebd7ed3fb5b32e8e3a92cf\", \"latency_unittests\": \"d4154ed4775d44d2caca93b3be600b26c6782ab0\", \"leveldb_service_unittests\": \"fbbded6cf231708ced99ffe3c57e76be61038095\", \"libjingle_xmpp_unittests\": \"2ea31fa4afdba80e7c34893fb533e6033e2aad77\", \"media_blink_unittests\": \"1f04a77e6b13229a2f543c1b2c27f29ead5281ad\", \"media_service_unittests\": \"d550a94178ea4512c95ea308a2d68faf0810af37\", \"media_unittests\": \"68393f2b1867c4b9042cd59cf337dcdf22801937\", \"message_center_unittests\": \"54acec1db8b88bb10b6bd108e99c0d644a4e94e9\", \"midi_unittests\": \"6857b61229f87f620dff79403b2060d8205500ca\", \"mojo_core_unittests\": \"ee3856da37d20590d6f65a4481ee5d70c7077d49\", \"mojo_unittests\": \"6916ccfa4ad8fb02b4e7fdc6a4e3fa3f7dcd536f\", \"nacl_helper_nonsfi_unittests\": \"28ed71d21278876205cbca17a2f21e3385f7f419\", \"nacl_loader_unittests\": \"35e96f0e8ecf3de34cc45a7d7a835e6ae6cdc3e6\", \"native_theme_unittests\": \"4f0b790bef85fa3f260edf7478e4f640d42ec099\", \"net_unittests\": \"9dcbd81be7ab4af55dd6628d0f59c8e75e7b71c9\", \"ozone_gl_unittests\": \"bdda822f8145be89bb23c5811324db3026c7a2fb\", \"ozone_unittests\": \"fc0f6121540be485337397989ce49ae4f957ea7b\", \"ozone_x11_unittests\": \"840d7b899189e5125b4fee04efa458a4c475c4e9\", \"pdf_unittests\": \"e48fdd3dd97344f1cc3671a9809dac921d1eb036\", \"perfetto_unittests\": \"43401de134982bc4662ce0cfb734c48b5d9261d6\", \"ppapi_unittests\": \"764c4f97be28d407c62ba3e1fe3498e3aa80f888\", \"printing_unittests\": \"416e37dc8979587cb5ab1ec842d4625cdd7764c1\", \"remoting_unittests\": \"d37706abc307417b1ebb53700e95bfb901f03611\", \"sandbox_linux_unittests\": \"e15ee6ab447ed95044370d6f6ec50dba05f7f02b\", \"service_manager_unittests\": \"525d07cbe1b476f91706ff3392a025ad9e0e8039\", \"services_unittests\": \"149c5f813f73c1ceff122ba16cbdff2b0ffeaef0\", \"shell_dialogs_unittests\": \"9c67fac62960710f90266e49271192f617442ad5\", \"skia_unittests\": \"02d677af09d9ca674707391eead61a01e81846f5\", \"snapshot_unittests\": \"469a58ead29ff4219deaa9de613f0b314aa69017\", \"sql_unittests\": \"32a96b95d815ea1f01776ac0dd450ce04f0f35e0\", \"storage_unittests\": \"f0b9a45fa92756ed3d44bb908c6a0437f864ecc1\", \"sync_integration_tests\": \"1cf894eaff90d830a3e3c543f821fd384888ebd0\", \"traffic_annotation_auditor_unittests\": \"ce44354092a2ffb21cd823bf8d4b972ce0a9658c\", \"ui_base_unittests\": \"13b8e0c7be28ea8ab138d5faf583e4a52820a8f5\", \"ui_chromeos_unittests\": \"9d14f8cf53407fc64c1da8464f1e9c556327d04b\", \"ui_touch_selection_unittests\": \"fb14b7461d059c83498555c4e5c4c75b5476ce5e\", \"unit_tests\": \"ea295e01fb1c9d32eb68375bcf243cf4e5303c39\", \"url_unittests\": \"1c471fdea2f5000b68329acb65ded6f84070923b\", \"usage_time_limit_unittests\": \"96ae8dde3d57dbabf9e16b68dec0a9a1db2cbd29\", \"views_unittests\": \"196189901a5a8342d96b09f6bfbd466e696029f9\", \"viz_unittests\": \"e5e8bd5ef2da76324590f99d129bd4727631fa7b\", \"wm_unittests\": \"53f6993f3c71b21253d2490950c33850be108ae5\", \"wtf_unittests\": \"ece976d61a40ccdf157e55080c4478c00828e477\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4277-e1590cf\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1556648634808983",
"status": "COMPLETED",
"status_changed_ts": "1556654209230257",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12144",
"builder:linux-chromeos-dbg",
"buildset:commit/git/ef19d59e601d35678abc909aaf830c35f4467982",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef19d59e601d35678abc909aaf830c35f4467982",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080159787317568752",
"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/8914729725151824992/+/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:448848c6ec6a8410",
"user_agent:luci-scheduler"
],
"updated_ts": "1556654209241389",
"url": "https://ci.chromium.org/b/8914729725151824992",
"utcnow_ts": "1556654210091615"
}
}
[19:56:50.101] Invocation finished in 1h39m25.922636473s with status SUCCEEDED