[16:10:57.322] New invocation is queued and will start shortly
[16:10:58.444] Starting the invocation (attempt 1)
[16:10:58.485] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:10:58.485] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:10:58.485] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029977023344840208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029977023344840208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"gitiles_ref:refs/heads/master"
]
}
[16:10:59.682] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604506258626638",
"id": "8864546961313318480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604506259442068",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42573",
"builder:linux-chromeos-rel",
"buildset:commit/git/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029977023344840208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864546961313318480/+/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": "1604506259442203",
"url": "https://ci.chromium.org/b/8864546961313318480",
"utcnow_ts": "1604506259660938"
}
}
[16:10:59.682] Task URL: https://ci.chromium.org/b/8864546961313318480
[16:10:59.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:2:0) after 1m0s
[16:11:02.618] Received PubSub notification, asking Buildbucket for the build status
[16:11:03.546] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:2:0)
[16:11:59.679] Timer tick, asking Buildbucket for the build status
[16:11:59.822] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:4:0) after 1m0s
[16:12:59.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:4:0)
[16:12:59.814] Timer tick, asking Buildbucket for the build status
[16:13:01.592] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:01.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:5:0) after 1m0s
[16:14:01.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:5:0)
[16:14:01.937] Timer tick, asking Buildbucket for the build status
[16:14:02.156] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:02.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:6:0) after 1m0s
[16:15:02.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:6:0)
[16:15:02.242] Timer tick, asking Buildbucket for the build status
[16:15:02.353] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:02.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:7:0) after 1m0s
[16:16:02.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:7:0)
[16:16:02.413] Timer tick, asking Buildbucket for the build status
[16:16:02.598] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:02.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:8:0) after 1m0s
[16:17:02.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:8:0)
[16:17:02.840] Timer tick, asking Buildbucket for the build status
[16:17:03.873] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:03.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:9:0) after 1m0s
[16:18:03.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:9:0)
[16:18:03.946] Timer tick, asking Buildbucket for the build status
[16:18:04.016] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:04.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:10:0) after 1m0s
[16:19:04.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:10:0)
[16:19:04.223] Timer tick, asking Buildbucket for the build status
[16:19:04.345] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:04.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:11:0) after 1m0s
[16:20:04.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:11:0)
[16:20:04.436] Timer tick, asking Buildbucket for the build status
[16:20:04.827] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:04.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:12:0) after 1m0s
[16:21:05.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:12:0)
[16:21:05.215] Timer tick, asking Buildbucket for the build status
[16:21:05.331] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:05.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:13:0) after 1m0s
[16:22:05.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:13:0)
[16:22:05.378] Timer tick, asking Buildbucket for the build status
[16:22:05.434] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:05.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:14:0) after 1m0s
[16:23:05.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:14:0)
[16:23:05.508] Timer tick, asking Buildbucket for the build status
[16:23:05.705] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:05.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:15:0) after 1m0s
[16:24:05.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:15:0)
[16:24:05.815] Timer tick, asking Buildbucket for the build status
[16:24:05.897] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:05.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:16:0) after 1m0s
[16:25:05.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:16:0)
[16:25:05.936] Timer tick, asking Buildbucket for the build status
[16:25:06.004] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:06.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:17:0) after 1m0s
[16:26:06.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:17:0)
[16:26:06.116] Timer tick, asking Buildbucket for the build status
[16:26:06.248] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:06.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:18:0) after 1m0s
[16:27:06.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:18:0)
[16:27:06.501] Timer tick, asking Buildbucket for the build status
[16:27:06.568] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:06.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:19:0) after 1m0s
[16:28:06.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:19:0)
[16:28:06.681] Timer tick, asking Buildbucket for the build status
[16:28:06.750] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:06.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:20:0) after 1m0s
[16:29:06.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:20:0)
[16:29:06.841] Timer tick, asking Buildbucket for the build status
[16:29:07.970] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:07.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:21:0) after 1m0s
[16:30:08.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:21:0)
[16:30:08.446] Timer tick, asking Buildbucket for the build status
[16:30:08.589] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:08.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:22:0) after 1m0s
[16:31:08.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:22:0)
[16:31:08.947] Timer tick, asking Buildbucket for the build status
[16:31:09.059] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:09.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:23:0) after 1m0s
[16:32:09.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:23:0)
[16:32:09.256] Timer tick, asking Buildbucket for the build status
[16:32:09.341] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:09.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:24:0) after 1m0s
[16:33:09.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:24:0)
[16:33:09.510] Timer tick, asking Buildbucket for the build status
[16:33:09.587] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:09.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:25:0) after 1m0s
[16:34:09.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:25:0)
[16:34:09.837] Timer tick, asking Buildbucket for the build status
[16:34:09.991] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:09.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:26:0) after 1m0s
[16:35:10.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:26:0)
[16:35:10.176] Timer tick, asking Buildbucket for the build status
[16:35:10.284] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:10.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:27:0) after 1m0s
[16:36:10.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:27:0)
[16:36:10.374] Timer tick, asking Buildbucket for the build status
[16:36:10.514] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:10.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:28:0) after 1m0s
[16:37:10.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:28:0)
[16:37:10.676] Timer tick, asking Buildbucket for the build status
[16:37:10.792] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:10.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:29:0) after 1m0s
[16:38:11.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:29:0)
[16:38:11.007] Timer tick, asking Buildbucket for the build status
[16:38:11.215] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:11.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:30:0) after 1m0s
[16:39:11.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:30:0)
[16:39:11.323] Timer tick, asking Buildbucket for the build status
[16:39:11.736] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:11.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:31:0) after 1m0s
[16:40:11.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:31:0)
[16:40:11.852] Timer tick, asking Buildbucket for the build status
[16:40:11.938] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:11.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:32:0) after 1m0s
[16:41:12.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:32:0)
[16:41:12.042] Timer tick, asking Buildbucket for the build status
[16:41:12.182] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:12.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:33:0) after 1m0s
[16:42:12.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:33:0)
[16:42:12.240] Timer tick, asking Buildbucket for the build status
[16:42:13.228] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:13.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:34:0) after 1m0s
[16:43:13.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:34:0)
[16:43:13.391] Timer tick, asking Buildbucket for the build status
[16:43:13.483] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:13.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:35:0) after 1m0s
[16:44:13.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:35:0)
[16:44:13.501] Timer tick, asking Buildbucket for the build status
[16:44:13.730] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:13.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:36:0) after 1m0s
[16:45:13.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:36:0)
[16:45:13.783] Timer tick, asking Buildbucket for the build status
[16:45:13.934] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:13.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:37:0) after 1m0s
[16:46:13.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:37:0)
[16:46:13.982] Timer tick, asking Buildbucket for the build status
[16:46:14.208] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:14.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:38:0) after 1m0s
[16:47:14.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:38:0)
[16:47:14.281] Timer tick, asking Buildbucket for the build status
[16:47:14.580] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:14.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:39:0) after 1m0s
[16:48:14.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:39:0)
[16:48:14.672] Timer tick, asking Buildbucket for the build status
[16:48:14.746] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:14.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:40:0) after 1m0s
[16:49:14.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:40:0)
[16:49:14.773] Timer tick, asking Buildbucket for the build status
[16:49:14.864] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:14.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:41:0) after 1m0s
[16:50:14.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:41:0)
[16:50:14.939] Timer tick, asking Buildbucket for the build status
[16:50:15.309] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:15.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:42:0) after 1m0s
[16:51:15.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:42:0)
[16:51:15.396] Timer tick, asking Buildbucket for the build status
[16:51:17.115] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:17.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:43:0) after 1m0s
[16:52:17.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:43:0)
[16:52:17.214] Timer tick, asking Buildbucket for the build status
[16:52:17.311] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:17.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:44:0) after 1m0s
[16:53:17.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:44:0)
[16:53:17.371] Timer tick, asking Buildbucket for the build status
[16:53:17.656] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:17.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:45:0) after 1m0s
[16:54:17.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:45:0)
[16:54:17.695] Timer tick, asking Buildbucket for the build status
[16:54:17.804] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:17.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:46:0) after 1m0s
[16:55:17.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:46:0)
[16:55:17.923] Timer tick, asking Buildbucket for the build status
[16:55:17.993] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:17.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:47:0) after 1m0s
[16:56:18.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:47:0)
[16:56:18.027] Timer tick, asking Buildbucket for the build status
[16:56:18.820] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:18.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:48:0) after 1m0s
[16:57:18.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:48:0)
[16:57:18.871] Timer tick, asking Buildbucket for the build status
[16:57:19.058] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:19.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:49:0) after 1m0s
[16:58:19.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:49:0)
[16:58:19.185] Timer tick, asking Buildbucket for the build status
[16:58:19.497] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:19.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:50:0) after 1m0s
[16:59:19.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:50:0)
[16:59:19.538] Timer tick, asking Buildbucket for the build status
[16:59:19.738] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:19.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:51:0) after 1m0s
[17:00:19.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:51:0)
[17:00:19.796] Timer tick, asking Buildbucket for the build status
[17:00:20.418] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:20.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:52:0) after 1m0s
[17:01:20.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:52:0)
[17:01:20.532] Timer tick, asking Buildbucket for the build status
[17:01:20.678] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:20.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:53:0) after 1m0s
[17:02:20.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:53:0)
[17:02:20.705] Timer tick, asking Buildbucket for the build status
[17:02:20.826] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:20.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:54:0) after 1m0s
[17:03:20.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:54:0)
[17:03:20.983] Timer tick, asking Buildbucket for the build status
[17:03:21.326] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:21.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:55:0) after 1m0s
[17:04:21.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:55:0)
[17:04:21.376] Timer tick, asking Buildbucket for the build status
[17:04:25.414] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:25.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:56:0) after 1m0s
[17:05:25.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:56:0)
[17:05:25.666] Timer tick, asking Buildbucket for the build status
[17:05:25.813] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:25.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:57:0) after 1m0s
[17:06:26.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:57:0)
[17:06:26.077] Timer tick, asking Buildbucket for the build status
[17:06:26.296] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:26.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:58:0) after 1m0s
[17:07:26.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:58:0)
[17:07:26.327] Timer tick, asking Buildbucket for the build status
[17:07:26.827] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:26.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:59:0) after 1m0s
[17:08:26.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:59:0)
[17:08:26.869] Timer tick, asking Buildbucket for the build status
[17:08:26.999] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:26.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:60:0) after 1m0s
[17:09:27.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:60:0)
[17:09:27.058] Timer tick, asking Buildbucket for the build status
[17:09:27.610] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:27.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:61:0) after 1m0s
[17:10:27.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:61:0)
[17:10:27.651] Timer tick, asking Buildbucket for the build status
[17:10:28.527] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:28.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:62:0) after 1m0s
[17:11:28.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:62:0)
[17:11:28.593] Timer tick, asking Buildbucket for the build status
[17:11:28.742] Build 8864546961313318480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:28.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029977023344840208:63:0) after 1m0s
[17:12:11.216] Received PubSub notification, asking Buildbucket for the build status
[17:12:11.739] Build 8864546961313318480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:12:11.739] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604509929427762",
"created_by": "project:chromium",
"created_ts": "1604506258626638",
"id": "8864546961313318480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\"}}",
"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\": \"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-43-vzsd\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42573, \"got_angle_revision\": \"265c5fa9a7e07ef0a0af6ed61d5682dae4fc31bd\", \"got_dawn_revision\": \"9d6265bc072546e92c1cea293e209e079d7345fb\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\", \"got_revision_cp\": \"refs/heads/master@{#824000}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"19f01495a8a18f2d222c29367901c7c1045ba4a2\", \"got_v8_revision\": \"f014ab9299eed4f879b06b06873e7eac3b20b0e5\", \"got_v8_revision_cp\": \"refs/heads/8.8.227@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d\", \"swarm_hashes_refs/heads/master(at){#824000}_without_patch\": {\"absl_hardening_tests\": \"09b9d914d5328428ad2cdcfcdfa1ef77d3be194e\", \"accessibility_unittests\": \"afe227b2ba9adb93c84352b322a711c095384bc0\", \"angle_unittests\": \"a1dbff6d33d00a5b0501c90948d722e7237267a9\", \"app_list_unittests\": \"e8a9991f5946c2b4450a20f1d1195bb118871474\", \"app_shell_unittests\": \"9523d22bf5da00a4c0cb5b8ed4d1dba3170640ae\", \"ash_unittests\": \"b71925aeef340dae1b0197bd20c150ae45395045\", \"aura_unittests\": \"23abb25f667b43f76d4e76251796247467d8fc2c\", \"base_unittests\": \"52f9eec6d9501dbc2bea45877bcb0ab3f892b25e\", \"base_util_unittests\": \"bad22223b6551d9ba8d5cc594dc41ca2af6709ea\", \"blink_common_unittests\": \"9ba3f79f45c621ad379a5f32bb7cf8441650e238\", \"blink_fuzzer_unittests\": \"0f098d3b847f72962e81b832078604690c9e4051\", \"blink_heap_unittests\": \"db431c58f9bed1afa2e77075c849af701dc19353\", \"blink_platform_unittests\": \"ed39a492b99a3d50541086b9e441d57adc13c17c\", \"blink_unittests\": \"77e9d42c23b921c4e5346fb7a18fc333b5ccee9a\", \"boringssl_crypto_tests\": \"cb0ed00f1ba25b21fa77977260b18d2c9e02fa24\", \"boringssl_ssl_tests\": \"19989a88dc968c646bfa49544df8e05597aa87fb\", \"browser_tests\": \"6c40887a8818c2233329f0fe09a2b037ffa8ea97\", \"capture_unittests\": \"5f630a5f8ca861ddf35f844b5bc4a37e08ccf436\", \"cast_unittests\": \"a12937ec62aa70564a4bcb7d342c40a929e8e065\", \"cc_unittests\": \"0e66e6e62cee704c6b80922d457fa79bbd97e100\", \"chrome_app_unittests\": \"735a7792c990138863caba8ae8981ced8b953ce9\", \"chromedriver_unittests\": \"a8e5ee244a7c8784e958f20332e4d23cbd012c99\", \"chromeos_components_unittests\": \"ee8f49d6b1e71fa4c1c0cfda6c8824bfaa7c015b\", \"chromeos_unittests\": \"2301ca226679f345278adcad4599f025e9e86990\", \"components_browsertests\": \"392f82042455e81121da16b2b21496606d4544d0\", \"components_unittests\": \"4c55230ca590e9956a77cb031bcbe7ab8f8f669d\", \"compositor_unittests\": \"1d14d09f9af053875c30f82e1f150c40a157f7be\", \"content_browsertests\": \"f3fd98a90fd7ab74fbd109018a4ff6eb6b92efd9\", \"content_unittests\": \"9e4d6fb2ce108c1ed32620fd737c5e191ad06e18\", \"crypto_unittests\": \"e41cb7e855ecbe10c5487da2a4ac8943babec6dc\", \"dbus_unittests\": \"55da6edb46e29d6615cb7d7838baa934a56b8d50\", \"device_unittests\": \"0cc4e8de2aefa9620b7961c70293101db1282c95\", \"display_unittests\": \"4b53dbec884fc1e1f902347e7e5e1504d982a100\", \"events_unittests\": \"58dd28f5e792ec9a4cb44ede21d0556330fb06ad\", \"exo_unittests\": \"2488e6645b1a87c74fa1886a3eb675a3b55d49bc\", \"extensions_browsertests\": \"126c263a7bbfae56ef5ba27b59f95162bb92a00f\", \"extensions_unittests\": \"061a76f07d6f0fa198a2c782df67424e3bf99e05\", \"filesystem_service_unittests\": \"cf2f6884088c797b616347649dc97255ae25be8d\", \"gcm_unit_tests\": \"de2c8cec0e7cb65b0c5cdbbb1aa956912c9da9c3\", \"gfx_unittests\": \"078c0926846545abe5bc5ab17e9b5f4ec6caa37d\", \"gin_unittests\": \"97c7e375a19ea9ae8a20ef505fa66392e4e2639c\", \"gl_unittests_ozone\": \"f8bfa1e19bed22f400672ff99036afb5ec9598e7\", \"google_apis_unittests\": \"9140887eeb611d8b03065030483648868ad3b117\", \"gpu_unittests\": \"e6771466db74f69fd90d41abce6c5e130a32e8ca\", \"gwp_asan_unittests\": \"25edab2ada0271e45bae1b36e544f8dbe836c255\", \"interactive_ui_tests\": \"32362e2c79f9d68115b2732e41557ba0446b3289\", \"ipc_tests\": \"a709c535c208323971d2cd4cf37226e879f786e8\", \"jingle_unittests\": \"175cc88f5ad42f4ef73c0fb88784434050eef9ac\", \"keyboard_unittests\": \"d2753572a7fab522f2bffbaa84522c287c9ea530\", \"latency_unittests\": \"6a776afb82e86cbb6cddc2995c5fedec9e425324\", \"libjingle_xmpp_unittests\": \"5717709d189b8fff30658311dff432eb20ff5313\", \"liburlpattern_unittests\": \"f71030a526e591d4842265025c2ef318554b703a\", \"media_blink_unittests\": \"edd4ad752576b6273e8ed3da4c7ba1d80523c0c7\", \"media_unittests\": \"431b60431174e862596052298e7b9472845aa1c7\", \"message_center_unittests\": \"ccc073692adeb647057c6f5ec34532194a66155a\", \"midi_unittests\": \"af669ffbb1b32f133699135e87577cabd3ce1685\", \"mojo_core_unittests\": \"febb3356f5abfcabbe82e9942934952442923bc5\", \"mojo_unittests\": \"93cc4f2127d29e213381749586838729ffe6dc21\", \"nacl_helper_nonsfi_unittests\": \"fa9335cc2c5b2dd0db4a43196397a889d2ac8066\", \"nacl_loader_unittests\": \"ac86e6039c1f77c430cf1ccf035d03346de75502\", \"native_theme_unittests\": \"837de9e93594af176ba835e029959b31eb3bf712\", \"net_unittests\": \"867dc3198daf531726f106d1419307c770154c99\", \"ozone_gl_unittests\": \"512df18f0c47e44e1df7f3c9cb0be138e82f0421\", \"ozone_unittests\": \"3f1c808866acab060fb5a7ef0126b26b4c9231d2\", \"ozone_x11_unittests\": \"af5bca33ae8b838bb27cc9cf26e54b4c8d7d421e\", \"pdf_unittests\": \"0f66b0197d1ae08ebab566a7d5b01d5c0fb7afe1\", \"perfetto_unittests\": \"799ae75a7025ad40e9c5376980b413445e5d8d83\", \"ppapi_unittests\": \"614c43a8bc0f935df0658bb3e6717e47da3c0705\", \"printing_unittests\": \"e444557a5cdcac92547cb80c6ee2c2e0d607a80d\", \"remoting_unittests\": \"47b301c6abf9764bf5c4cf25e66af76121512135\", \"sandbox_linux_unittests\": \"50d62785cf2602c2ed6712f6bb66955f69c2bf67\", \"service_manager_unittests\": \"800fd2e039f80e1e6d0b47740a02ba7453f98cb2\", \"services_unittests\": \"9187bae739a2978da50bc5c0cae66ce24d48d740\", \"shell_dialogs_unittests\": \"aabd910e9abca304c28d0f9347aa587e7e34b0f9\", \"skia_unittests\": \"aed233427d95fb079884a4150dc02b2ac4b4582f\", \"snapshot_unittests\": \"5314f57a327b940d066861a94109dc29b0beb764\", \"sql_unittests\": \"0c5066115e0873b7d7c3653cff4979c1d7701087\", \"storage_unittests\": \"000308c2296b509cb6e23b0672e0885ab1a29863\", \"sync_integration_tests\": \"13b3ec715f32468c091845cc3a1993578273b828\", \"traffic_annotation_auditor_unittests\": \"3b501a5171ef8dd2bb3f8fe78125f4929c3b0de6\", \"ui_base_unittests\": \"3984f8a1c4626b1ae8fae534df7a757bd26cc5bc\", \"ui_chromeos_unittests\": \"578bf09b55ef4ced471fa09727e2a9568ea6d3b2\", \"ui_touch_selection_unittests\": \"a6f90845151c47c74febf8202dc7ade78fa26a92\", \"unit_tests\": \"de9d1cf4b1ff48038d109865d7b44380587792c2\", \"url_unittests\": \"644e6dee0181abace2e5368e559819997ff6ffc2\", \"usage_time_limit_unittests\": \"e9d83535514ec5f5f56c75b462575db70aceed58\", \"views_unittests\": \"3f6a018956038038f9c8181ce34277f3ee8c70b6\", \"viz_unittests\": \"524ab9d983b97a284a2188a87ecb736514f2f278\", \"wayland_client_perftests\": \"bf2e0c51965b5afb6cdb8deb01a1d0501ef145b9\", \"wm_unittests\": \"e5a3f513afc12c667a2ef8c73034587b5b7e167a\", \"wtf_unittests\": \"4efb1bfb1a23de7dbafdf585ffbb818df9d6d3ca\", \"zlib_unittests\": \"24dd18054183c2f5a28a7597d4b52b5849b41c95\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_642e8105f745eb1a71ee53fae678c471391c0152dcfea24d527182ac47960a08_v2\", \"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_bea46eaeedb7de1bd52f00a0308b818fe481ae542888a2fa5825ab1ef5ba3493_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-43-vzsd\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604506261583187",
"status": "COMPLETED",
"status_changed_ts": "1604509930608862",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42573",
"builder:linux-chromeos-rel",
"buildset:commit/git/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ba7081c6bf10e5eec6df26c898ff5bc9d847d1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029977023344840208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864546961313318480/+/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:4facd81ae35f8a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604509930620096",
"url": "https://ci.chromium.org/b/8864546961313318480",
"utcnow_ts": "1604509931727047"
}
}
[17:12:11.739] Invocation finished in 1h1m14.433994577s with status SUCCEEDED