[14:01:42.734] New invocation is queued and will start shortly
[14:01:44.131] Starting the invocation (attempt 1)
[14:01:44.207] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:01:44.207] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:01:44.207] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017935758095832528",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5371070da9c35f91c26c2b10c28ce8ff37a9121e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017935758095832528",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"gitiles_ref:refs/heads/master"
]
}
[14:01:45.397] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615989704370206",
"id": "8852505695708920544",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5371070da9c35f91c26c2b10c28ce8ff37a9121e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615989705080435",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46217",
"builder:linux-chromeos-rel",
"buildset:commit/git/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017935758095832528",
"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/8852505695708920544/+/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": "1615989705080552",
"url": "https://ci.chromium.org/b/8852505695708920544",
"utcnow_ts": "1615989705378486"
}
}
[14:01:45.397] Task URL: https://ci.chromium.org/b/8852505695708920544
[14:01:45.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:2:0) after 1m0s
[14:02:22.476] Received PubSub notification, asking Buildbucket for the build status
[14:02:22.838] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:45.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:2:0)
[14:02:45.412] Timer tick, asking Buildbucket for the build status
[14:02:45.472] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:45.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:4:0) after 1m0s
[14:03:45.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:4:0)
[14:03:45.485] Timer tick, asking Buildbucket for the build status
[14:03:45.562] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:45.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:5:0) after 1m0s
[14:04:45.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:5:0)
[14:04:45.771] Timer tick, asking Buildbucket for the build status
[14:04:45.846] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:45.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:6:0) after 1m0s
[14:05:45.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:6:0)
[14:05:45.901] Timer tick, asking Buildbucket for the build status
[14:05:45.964] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:45.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:7:0) after 1m0s
[14:06:46.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:7:0)
[14:06:46.026] Timer tick, asking Buildbucket for the build status
[14:06:46.107] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:46.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:8:0) after 1m0s
[14:07:46.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:8:0)
[14:07:46.150] Timer tick, asking Buildbucket for the build status
[14:07:46.355] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:46.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:9:0) after 1m0s
[14:08:46.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:9:0)
[14:08:46.365] Timer tick, asking Buildbucket for the build status
[14:08:46.436] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:46.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:10:0) after 1m0s
[14:09:46.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:10:0)
[14:09:46.477] Timer tick, asking Buildbucket for the build status
[14:09:46.549] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:46.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:11:0) after 1m0s
[14:10:46.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:11:0)
[14:10:46.561] Timer tick, asking Buildbucket for the build status
[14:10:46.632] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:46.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:12:0) after 1m0s
[14:11:46.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:12:0)
[14:11:46.658] Timer tick, asking Buildbucket for the build status
[14:11:46.800] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:46.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:13:0) after 1m0s
[14:12:46.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:13:0)
[14:12:46.923] Timer tick, asking Buildbucket for the build status
[14:12:47.023] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:14:0) after 1m0s
[14:13:47.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:14:0)
[14:13:47.137] Timer tick, asking Buildbucket for the build status
[14:13:47.201] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:47.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:15:0) after 1m0s
[14:14:47.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:15:0)
[14:14:47.447] Timer tick, asking Buildbucket for the build status
[14:14:47.608] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:47.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:16:0) after 1m0s
[14:15:47.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:16:0)
[14:15:47.650] Timer tick, asking Buildbucket for the build status
[14:15:47.714] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:47.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:17:0) after 1m0s
[14:16:47.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:17:0)
[14:16:47.760] Timer tick, asking Buildbucket for the build status
[14:16:47.836] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:47.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:18:0) after 1m0s
[14:17:48.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:18:0)
[14:17:48.033] Timer tick, asking Buildbucket for the build status
[14:17:48.103] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:48.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:19:0) after 1m0s
[14:18:48.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:19:0)
[14:18:48.232] Timer tick, asking Buildbucket for the build status
[14:18:48.325] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:20:0) after 1m0s
[14:19:48.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:20:0)
[14:19:48.316] Timer tick, asking Buildbucket for the build status
[14:19:48.442] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:48.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:21:0) after 1m0s
[14:20:48.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:21:0)
[14:20:48.479] Timer tick, asking Buildbucket for the build status
[14:20:48.682] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:22:0) after 1m0s
[14:21:48.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:22:0)
[14:21:48.700] Timer tick, asking Buildbucket for the build status
[14:21:48.775] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:23:0) after 1m0s
[14:22:48.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:23:0)
[14:22:48.834] Timer tick, asking Buildbucket for the build status
[14:22:48.915] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:48.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:24:0) after 1m0s
[14:23:49.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:24:0)
[14:23:49.015] Timer tick, asking Buildbucket for the build status
[14:23:49.097] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:49.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:25:0) after 1m0s
[14:24:49.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:25:0)
[14:24:49.095] Timer tick, asking Buildbucket for the build status
[14:24:49.177] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:49.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:26:0) after 1m0s
[14:25:49.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:26:0)
[14:25:49.200] Timer tick, asking Buildbucket for the build status
[14:25:49.351] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:49.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:27:0) after 1m0s
[14:26:49.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:27:0)
[14:26:49.357] Timer tick, asking Buildbucket for the build status
[14:26:49.517] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:49.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:28:0) after 1m0s
[14:27:49.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:28:0)
[14:27:49.588] Timer tick, asking Buildbucket for the build status
[14:27:49.650] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:49.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:29:0) after 1m0s
[14:28:49.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:29:0)
[14:28:49.659] Timer tick, asking Buildbucket for the build status
[14:28:49.800] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:49.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:30:0) after 1m0s
[14:29:49.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:30:0)
[14:29:49.848] Timer tick, asking Buildbucket for the build status
[14:29:50.079] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:50.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:31:0) after 1m0s
[14:30:50.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:31:0)
[14:30:50.109] Timer tick, asking Buildbucket for the build status
[14:30:50.322] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:50.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:32:0) after 1m0s
[14:31:50.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:32:0)
[14:31:50.345] Timer tick, asking Buildbucket for the build status
[14:31:50.811] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:50.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:33:0) after 1m0s
[14:32:50.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:33:0)
[14:32:50.820] Timer tick, asking Buildbucket for the build status
[14:32:50.959] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:50.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:34:0) after 1m0s
[14:33:50.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:34:0)
[14:33:50.963] Timer tick, asking Buildbucket for the build status
[14:33:51.341] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:51.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:35:0) after 1m0s
[14:34:51.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:35:0)
[14:34:51.377] Timer tick, asking Buildbucket for the build status
[14:34:51.613] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:51.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:36:0) after 1m0s
[14:35:51.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:36:0)
[14:35:51.746] Timer tick, asking Buildbucket for the build status
[14:35:52.112] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:52.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:37:0) after 1m0s
[14:36:52.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:37:0)
[14:36:52.173] Timer tick, asking Buildbucket for the build status
[14:36:52.264] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:52.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:38:0) after 1m0s
[14:37:52.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:38:0)
[14:37:52.293] Timer tick, asking Buildbucket for the build status
[14:37:52.392] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:39:0) after 1m0s
[14:38:52.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:39:0)
[14:38:52.420] Timer tick, asking Buildbucket for the build status
[14:38:52.506] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:40:0) after 1m0s
[14:39:52.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:40:0)
[14:39:52.526] Timer tick, asking Buildbucket for the build status
[14:39:52.736] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:52.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:41:0) after 1m0s
[14:40:52.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:41:0)
[14:40:52.714] Timer tick, asking Buildbucket for the build status
[14:40:52.793] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:42:0) after 1m0s
[14:41:52.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:42:0)
[14:41:52.818] Timer tick, asking Buildbucket for the build status
[14:41:52.930] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:43:0) after 1m0s
[14:42:52.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:43:0)
[14:42:52.961] Timer tick, asking Buildbucket for the build status
[14:42:53.041] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:53.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:44:0) after 1m0s
[14:43:53.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:44:0)
[14:43:53.136] Timer tick, asking Buildbucket for the build status
[14:43:53.206] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:53.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:45:0) after 1m0s
[14:44:53.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:45:0)
[14:44:53.278] Timer tick, asking Buildbucket for the build status
[14:44:53.517] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:53.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:46:0) after 1m0s
[14:45:53.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:46:0)
[14:45:53.584] Timer tick, asking Buildbucket for the build status
[14:45:54.004] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:54.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:47:0) after 1m0s
[14:46:54.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:47:0)
[14:46:54.172] Timer tick, asking Buildbucket for the build status
[14:46:54.275] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:54.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:48:0) after 1m0s
[14:47:54.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:48:0)
[14:47:54.431] Timer tick, asking Buildbucket for the build status
[14:47:54.510] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:54.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:49:0) after 1m0s
[14:48:54.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:49:0)
[14:48:54.482] Timer tick, asking Buildbucket for the build status
[14:48:54.565] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:54.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:50:0) after 1m0s
[14:49:54.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:50:0)
[14:49:54.835] Timer tick, asking Buildbucket for the build status
[14:49:54.978] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:54.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:51:0) after 1m0s
[14:50:55.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:51:0)
[14:50:55.218] Timer tick, asking Buildbucket for the build status
[14:50:55.286] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:55.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:52:0) after 1m0s
[14:51:55.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:52:0)
[14:51:55.331] Timer tick, asking Buildbucket for the build status
[14:51:55.438] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:55.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:53:0) after 1m0s
[14:52:55.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:53:0)
[14:52:55.559] Timer tick, asking Buildbucket for the build status
[14:52:55.640] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:55.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:54:0) after 1m0s
[14:53:55.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:54:0)
[14:53:55.670] Timer tick, asking Buildbucket for the build status
[14:53:55.758] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:55.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:55:0) after 1m0s
[14:54:55.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:55:0)
[14:54:55.832] Timer tick, asking Buildbucket for the build status
[14:54:55.919] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:55.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:56:0) after 1m0s
[14:55:55.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:56:0)
[14:55:55.970] Timer tick, asking Buildbucket for the build status
[14:55:56.060] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:56.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:57:0) after 1m0s
[14:56:56.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:57:0)
[14:56:56.108] Timer tick, asking Buildbucket for the build status
[14:56:56.259] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:56.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:58:0) after 1m0s
[14:57:56.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:58:0)
[14:57:56.311] Timer tick, asking Buildbucket for the build status
[14:57:56.382] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:59:0) after 1m0s
[14:58:56.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:59:0)
[14:58:56.415] Timer tick, asking Buildbucket for the build status
[14:58:56.739] Build 8852505695708920544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:56.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017935758095832528:60:0) after 1m0s
[14:58:57.122] Received PubSub notification, asking Buildbucket for the build status
[14:58:57.211] Build 8852505695708920544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:58:57.211] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615993135811578",
"created_by": "project:chromium",
"created_ts": "1615989704370206",
"id": "8852505695708920544",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5371070da9c35f91c26c2b10c28ce8ff37a9121e\"}}",
"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\": \"5371070da9c35f91c26c2b10c28ce8ff37a9121e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-166-3f40\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46217, \"got_angle_revision\": \"b3394db36779b618b824d91f52cfd3dc3136c21f\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"5371070da9c35f91c26c2b10c28ce8ff37a9121e\", \"got_revision_cp\": \"refs/heads/master@{#863745}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"07c60e0c75db7c20980bbc2aa51f3468985a60da\", \"got_v8_revision_cp\": \"refs/heads/9.1.142@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5371070da9c35f91c26c2b10c28ce8ff37a9121e\", \"swarm_hashes_refs/heads/master(at){#863745}_without_patch\": {\"absl_hardening_tests\": \"a3b32ebe0c192454fd9173a083cf5c009b62b50a\", \"accessibility_unittests\": \"19715526226cfb0680ade68364fe777fe814a610\", \"angle_unittests\": \"4f866421600f9d80389ebf32fb52d805c4b6ee65\", \"app_list_unittests\": \"ce670ca68da446cb988acbade7cefe63066fbf1e\", \"app_shell_unittests\": \"648e15eb3920f4bccf0aaafb33ed009c55a714eb\", \"ash_unittests\": \"2dcd38e5732c6ef14a8092f7b63fffcb2de6446d\", \"aura_unittests\": \"87f85d55d22cae2b421ba2a5e8ca0e3dfc2d8268\", \"base_unittests\": \"1bf1bdfa35248fcd9187cdc3a0ddb23fb84a1584\", \"base_util_unittests\": \"78ceeb44ea64fd50d4e9c91be89507d14e54ab73\", \"blink_common_unittests\": \"1b4306770ce1700904b10e55e0a52da0fbe464c8\", \"blink_fuzzer_unittests\": \"f75cbbe467f0be4975e629af5bde2dbb4b80a5d8\", \"blink_heap_unittests\": \"415b1a4cde1d6e033323cacbfaa86d5e5cf97b15\", \"blink_platform_unittests\": \"3f42d8991b6c386c4cb44004476ce3e8652eda05\", \"blink_unittests\": \"2f374d9fcdaff5928335bec3418fc50f58487d05\", \"boringssl_crypto_tests\": \"a145be11b1db1ebc7469dd4c9d1f41fcad89617d\", \"boringssl_ssl_tests\": \"6cba9e5e61dca7cda0a1c263e9f4162efc951b0b\", \"browser_tests\": \"97844244d80d92087cf13ddb723d87a4f6f23825\", \"capture_unittests\": \"885a181199c9f107efcdb59668f96974e241f556\", \"cast_unittests\": \"a64f5c88c9f1e15d773590a45f5c7fc26c48bf99\", \"cc_unittests\": \"dbf0a97be615503f62f0806268ec5bbcd429101e\", \"chrome_app_unittests\": \"c1769bec14749b7cd9e97776b1316ea6c5da2f07\", \"chromedriver_unittests\": \"b8713b05b2e4fb51b25d205138dcb19762da2397\", \"chromeos_components_unittests\": \"4ac50156d678529683db7b945542e42f94ad99ce\", \"chromeos_unittests\": \"aed4576219d7cab6eb10c36a936f7b935dcf3df9\", \"color_unittests\": \"f2e016254de63a61988f89d2eef6b279f3ce8362\", \"components_browsertests\": \"4d0a1e3de1f2d91d93cbd2a83b5eb7a3d1659966\", \"components_unittests\": \"af2c7eaa7054b694a09fec04c14e5780fd81c203\", \"compositor_unittests\": \"bf7645bda684c9b9c9af5e2db7574905cf7e4c16\", \"content_browsertests\": \"245361388275db1093f136dff4e996882801545c\", \"content_unittests\": \"efe1c3e12043129ada13551071f5230553d6500e\", \"crashpad_tests\": \"6e0b16d86ac7c6b64a225dbe7c6d896d996bb36b\", \"crypto_unittests\": \"2eb7ef2e0bfee161f29a3995b7e4928b29012f1f\", \"dbus_unittests\": \"45b3bd069dd021745e1fb8d683e8a7e1cbb804d8\", \"device_unittests\": \"9aeef21f035f6d7044002674182ede35118342ff\", \"display_unittests\": \"1e75e541d956d60bcbd651c9a77ed344d7ad88bf\", \"events_unittests\": \"9edc492abb455b673bcdeff502464412f53a3eeb\", \"exo_unittests\": \"0ffeea6d0bd58201766d92e7efdfe65b9611443d\", \"extensions_browsertests\": \"2060ad6a5b445119a250c74127417dcf4e43ca40\", \"extensions_unittests\": \"7206ee3934e6ebbc68cc1ca5697a5981fa48d354\", \"filesystem_service_unittests\": \"4d34daace545b3d20724d647b662b1be37c99750\", \"gcm_unit_tests\": \"84208a0c8270f9d031fa0f54192a63da6c73ec17\", \"gfx_unittests\": \"0b942d746fcb2668661bb98ccc447dec0fb47a6b\", \"gin_unittests\": \"691def7430e97e60bc774e2ce11ccf68767ccb62\", \"gl_unittests_ozone\": \"1cdc644c2105ad84557be8cf08aa6004235d6450\", \"google_apis_unittests\": \"ffd7c7708eab25e60ca4b1362c5b5990e5c0984c\", \"gpu_unittests\": \"ec998e222fcc4e43869bd6469df11a3c58750679\", \"gwp_asan_unittests\": \"10ff4cc325b7b3d923e4b813852bd56ea5b86458\", \"interactive_ui_tests\": \"63a1574e79c4638d5f5b2e77781e46d1aa0ad201\", \"ipc_tests\": \"fef4652fa52598ec0f1832af3dc89becf6058537\", \"jingle_unittests\": \"77ac42eb1976e2116f0fa674b2a9aae6636e289f\", \"keyboard_unittests\": \"ad8c1e3bea9369279376dc9e50d2ae5a438b9c4f\", \"latency_unittests\": \"8942bed6cb0feb8cf608804fdc0f810d9d6aa331\", \"libjingle_xmpp_unittests\": \"6393300360d73d146a3e2246818e5c3b8bbf17d3\", \"liburlpattern_unittests\": \"4269e1b49f8ffc9b79cdd57036361c088fd39a9f\", \"media_blink_unittests\": \"c0faed0fa6b5914c38febf2a1b6fd34439153a2c\", \"media_unittests\": \"dd409c4eb80869a5e20946a9f506d4b29f809db9\", \"message_center_unittests\": \"5df5fd537c4a881b14965036aff89e7a7c44f6c8\", \"midi_unittests\": \"f84600fb3d9f40ff97057c539565dfab9c9e9c88\", \"mojo_core_unittests\": \"e8343b661454074908253e4977d9be6bbfc64374\", \"mojo_unittests\": \"e02724588efbbdcb73054f3ff1b97f883a65c501\", \"nacl_helper_nonsfi_unittests\": \"a527816f12204df55671e07dea71b86049606555\", \"nacl_loader_unittests\": \"fa091ba707f1a1801264d6a38787fc410cbd9e80\", \"native_theme_unittests\": \"0a6b8e230447f3fc7bafd86cf86b6958545bda58\", \"net_unittests\": \"6db7b6929b13ba71020d459cf70c805dc43bfb1b\", \"ozone_gl_unittests\": \"38a1a9ce12d330effa9f223d5c3b1603cec9792c\", \"ozone_unittests\": \"3d476687295a391c80564ec8aa2996448f858245\", \"ozone_x11_unittests\": \"a1d076f71d6e5584956aa23875d8d71186029cae\", \"pdf_unittests\": \"aeb7abc5c9c4420463a93dad4931c3cd78cc849e\", \"perfetto_unittests\": \"bb63d02325e6b7d1bad181485434f9bb6581ba0a\", \"ppapi_unittests\": \"f7f9e8687685326f423995ba756582b562ce3759\", \"printing_unittests\": \"97fc8508e7ad6e6b4333635ee10394ba1404b598\", \"remoting_unittests\": \"cc4061be873dbbee53d84c1ef846bfc9b7281e75\", \"sandbox_linux_unittests\": \"aeb965015e27a2235a1da735946767b40ae8420b\", \"service_manager_unittests\": \"5a524d7a64d5eb6282223bea202408a2b1d95d12\", \"services_unittests\": \"3b4ee1ab4945fec5fe3ca38ca8cf35a5681dacb9\", \"shell_dialogs_unittests\": \"2fbd74a1810a00a26749ebf6414be92214f47790\", \"shell_encryption_unittests\": \"01f7f363ad224aef7febf77458551d92c15f3e8e\", \"skia_unittests\": \"7ec83eb6a7fbcfef09737e0fcd51f40a9f2cc9f7\", \"snapshot_unittests\": \"128f0eede3dc4518439ae42f432f3e8474377b51\", \"sql_unittests\": \"c29abc8eb89f9cb76748e06a37380dbdb7ffbedf\", \"storage_unittests\": \"acc0cf014b5309f689c8c959bb67293897387b64\", \"sync_integration_tests\": \"18103a7c6344ec4ff7fd979395d2ad5eac536486\", \"traffic_annotation_auditor_unittests\": \"ae124899fa68eb916d8835dd028cd3e57d1c1769\", \"ui_base_unittests\": \"fea85bf1e3e47a421dcfb57d9ae7b3c119a15c71\", \"ui_chromeos_unittests\": \"2dea87cb57207b75d0a40f4fe5bb6a29f217b1a1\", \"ui_touch_selection_unittests\": \"89ac811d76619d838f025ee715971b4c9a849c6e\", \"unit_tests\": \"521fc061f511b9326904319ef33e9284621b8643\", \"url_unittests\": \"6d0e8b08317a6bd4b4889351231ff4c1f0d384a2\", \"usage_time_limit_unittests\": \"5906a5d2f3268d8715fd86c12f7515fdb6351602\", \"views_unittests\": \"ee265cf276b992e39a7083575a9a1ec2f11f37b5\", \"viz_unittests\": \"827af3cb92510e4110c1fc2bca4a087f0a0f96b1\", \"wayland_client_perftests\": \"3642b56366bb765b00f01629043e8c322e2e1267\", \"wayland_client_tests\": \"e756e94d7cee8615e9d5199eb5307c3bd46dcdf5\", \"wm_unittests\": \"1a8ce72cedb1b587fbbbe6b54dda5731a751a2a3\", \"wtf_unittests\": \"049f4f6cb69b36e23f1910329b313a40544bc884\", \"zlib_unittests\": \"87f385f01169acfc3796ae7f2a2baf693343b7e7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_304607b978b8f4bcd63bac0f543c3a69f6b4c7cf202dcd2d3c5b8d4a70be201e_v2\", \"builder_6440ef9dedd0dc3b34dcb22ab34f3415d4cc49eb5cc8c1aeab1a9757364e9da5_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-166-3f40\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615989740438221",
"status": "COMPLETED",
"status_changed_ts": "1615993136097180",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46217",
"builder:linux-chromeos-rel",
"buildset:commit/git/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5371070da9c35f91c26c2b10c28ce8ff37a9121e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017935758095832528",
"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/8852505695708920544/+/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:52594f929e7b0d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615993136102076",
"url": "https://ci.chromium.org/b/8852505695708920544",
"utcnow_ts": "1615993137200353"
}
}
[14:58:57.211] Invocation finished in 57m14.50021359s with status SUCCEEDED