[16:25:52.436] New invocation is queued and will start shortly
[16:25:53.487] Starting the invocation (attempt 1)
[16:25:53.536] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:25:53.536] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:25:53.536] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020463403279083136",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9020463403279083136",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"gitiles_ref:refs/heads/master"
]
}
[16:25:54.411] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613579153633631",
"id": "8855033341358729552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613579154119141",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45501",
"builder:linux-chromeos-rel",
"buildset:commit/git/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020463403279083136",
"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/8855033341358729552/+/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": "1613579154119290",
"url": "https://ci.chromium.org/b/8855033341358729552",
"utcnow_ts": "1613579154390650"
}
}
[16:25:54.412] Task URL: https://ci.chromium.org/b/8855033341358729552
[16:25:54.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:2:0) after 1m0s
[16:25:56.694] Received PubSub notification, asking Buildbucket for the build status
[16:25:57.123] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:54.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:2:0)
[16:26:54.481] Timer tick, asking Buildbucket for the build status
[16:26:54.704] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:54.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:4:0) after 1m0s
[16:27:54.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:4:0)
[16:27:54.722] Timer tick, asking Buildbucket for the build status
[16:27:54.824] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:54.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:5:0) after 1m0s
[16:28:54.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:5:0)
[16:28:54.929] Timer tick, asking Buildbucket for the build status
[16:28:55.021] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:55.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:6:0) after 1m0s
[16:29:55.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:6:0)
[16:29:55.106] Timer tick, asking Buildbucket for the build status
[16:29:55.203] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:55.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:7:0) after 1m0s
[16:30:55.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:7:0)
[16:30:55.307] Timer tick, asking Buildbucket for the build status
[16:30:55.434] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:55.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:8:0) after 1m0s
[16:31:55.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:8:0)
[16:31:55.479] Timer tick, asking Buildbucket for the build status
[16:31:55.771] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:55.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:9:0) after 1m0s
[16:32:55.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:9:0)
[16:32:55.776] Timer tick, asking Buildbucket for the build status
[16:32:55.849] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:55.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:10:0) after 1m0s
[16:33:55.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:10:0)
[16:33:55.902] Timer tick, asking Buildbucket for the build status
[16:33:56.136] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:56.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:11:0) after 1m0s
[16:34:56.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:11:0)
[16:34:56.123] Timer tick, asking Buildbucket for the build status
[16:34:56.534] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:56.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:12:0) after 1m0s
[16:35:56.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:12:0)
[16:35:56.576] Timer tick, asking Buildbucket for the build status
[16:35:56.754] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:56.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:13:0) after 1m0s
[16:36:57.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:13:0)
[16:36:57.207] Timer tick, asking Buildbucket for the build status
[16:36:57.325] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:57.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:14:0) after 1m0s
[16:37:57.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:14:0)
[16:37:57.407] Timer tick, asking Buildbucket for the build status
[16:37:57.615] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:57.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:15:0) after 1m0s
[16:38:57.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:15:0)
[16:38:57.615] Timer tick, asking Buildbucket for the build status
[16:38:57.773] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:57.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:16:0) after 1m0s
[16:39:57.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:16:0)
[16:39:57.783] Timer tick, asking Buildbucket for the build status
[16:39:57.974] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:57.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:17:0) after 1m0s
[16:40:58.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:17:0)
[16:40:58.133] Timer tick, asking Buildbucket for the build status
[16:40:58.215] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:58.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:18:0) after 1m0s
[16:41:58.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:18:0)
[16:41:58.354] Timer tick, asking Buildbucket for the build status
[16:41:58.744] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:58.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:19:0) after 1m0s
[16:42:58.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:19:0)
[16:42:58.783] Timer tick, asking Buildbucket for the build status
[16:42:58.844] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:58.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:20:0) after 1m0s
[16:43:58.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:20:0)
[16:43:58.960] Timer tick, asking Buildbucket for the build status
[16:43:59.104] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:59.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:21:0) after 1m0s
[16:44:59.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:21:0)
[16:44:59.228] Timer tick, asking Buildbucket for the build status
[16:44:59.321] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:59.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:22:0) after 1m0s
[16:45:59.379] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:22:0)
[16:45:59.379] Timer tick, asking Buildbucket for the build status
[16:45:59.464] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:59.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:23:0) after 1m0s
[16:46:59.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:23:0)
[16:46:59.503] Timer tick, asking Buildbucket for the build status
[16:46:59.627] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:59.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:24:0) after 1m0s
[16:47:59.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:24:0)
[16:47:59.654] Timer tick, asking Buildbucket for the build status
[16:47:59.728] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:59.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:25:0) after 1m0s
[16:48:59.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:25:0)
[16:48:59.808] Timer tick, asking Buildbucket for the build status
[16:48:59.966] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:59.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:26:0) after 1m0s
[16:50:00.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:26:0)
[16:50:00.037] Timer tick, asking Buildbucket for the build status
[16:50:00.195] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:27:0) after 1m0s
[16:51:00.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:27:0)
[16:51:00.215] Timer tick, asking Buildbucket for the build status
[16:51:00.292] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:00.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:28:0) after 1m0s
[16:52:00.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:28:0)
[16:52:00.383] Timer tick, asking Buildbucket for the build status
[16:52:00.482] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:00.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:29:0) after 1m0s
[16:53:00.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:29:0)
[16:53:00.466] Timer tick, asking Buildbucket for the build status
[16:53:00.535] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:00.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:30:0) after 1m0s
[16:54:00.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:30:0)
[16:54:00.858] Timer tick, asking Buildbucket for the build status
[16:54:00.954] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:00.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:31:0) after 1m0s
[16:55:01.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:31:0)
[16:55:01.567] Timer tick, asking Buildbucket for the build status
[16:55:01.807] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:01.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:32:0) after 1m0s
[16:56:01.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:32:0)
[16:56:01.777] Timer tick, asking Buildbucket for the build status
[16:56:02.006] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:02.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:33:0) after 1m0s
[16:57:02.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:33:0)
[16:57:02.091] Timer tick, asking Buildbucket for the build status
[16:57:02.234] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:02.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:34:0) after 1m0s
[16:58:02.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:34:0)
[16:58:02.261] Timer tick, asking Buildbucket for the build status
[16:58:02.581] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:02.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:35:0) after 1m0s
[16:59:02.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:35:0)
[16:59:02.625] Timer tick, asking Buildbucket for the build status
[16:59:02.745] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:02.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:36:0) after 1m0s
[17:00:02.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:36:0)
[17:00:02.725] Timer tick, asking Buildbucket for the build status
[17:00:02.838] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:02.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:37:0) after 1m0s
[17:01:02.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:37:0)
[17:01:02.897] Timer tick, asking Buildbucket for the build status
[17:01:03.258] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:03.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:38:0) after 1m0s
[17:02:03.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:38:0)
[17:02:03.310] Timer tick, asking Buildbucket for the build status
[17:02:03.565] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:03.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:39:0) after 1m0s
[17:03:03.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:39:0)
[17:03:03.600] Timer tick, asking Buildbucket for the build status
[17:03:04.336] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:04.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:40:0) after 1m0s
[17:04:04.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:40:0)
[17:04:04.391] Timer tick, asking Buildbucket for the build status
[17:04:04.483] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:04.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:41:0) after 1m0s
[17:05:04.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:41:0)
[17:05:04.596] Timer tick, asking Buildbucket for the build status
[17:05:04.793] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:42:0) after 1m0s
[17:06:04.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:42:0)
[17:06:04.851] Timer tick, asking Buildbucket for the build status
[17:06:05.102] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:05.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:43:0) after 1m0s
[17:07:05.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:43:0)
[17:07:05.145] Timer tick, asking Buildbucket for the build status
[17:07:05.287] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:05.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:44:0) after 1m0s
[17:08:05.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:44:0)
[17:08:05.313] Timer tick, asking Buildbucket for the build status
[17:08:05.418] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:05.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:45:0) after 1m0s
[17:09:05.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:45:0)
[17:09:05.463] Timer tick, asking Buildbucket for the build status
[17:09:05.632] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:05.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:46:0) after 1m0s
[17:10:05.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:46:0)
[17:10:05.625] Timer tick, asking Buildbucket for the build status
[17:10:05.775] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:05.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:47:0) after 1m0s
[17:11:05.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:47:0)
[17:11:05.797] Timer tick, asking Buildbucket for the build status
[17:11:05.897] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:05.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:48:0) after 1m0s
[17:12:05.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:48:0)
[17:12:05.943] Timer tick, asking Buildbucket for the build status
[17:12:06.470] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:06.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:49:0) after 1m0s
[17:13:06.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:49:0)
[17:13:06.507] Timer tick, asking Buildbucket for the build status
[17:13:06.610] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:06.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:50:0) after 1m0s
[17:14:06.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:50:0)
[17:14:06.633] Timer tick, asking Buildbucket for the build status
[17:14:06.744] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:06.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:51:0) after 1m0s
[17:15:06.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:51:0)
[17:15:06.798] Timer tick, asking Buildbucket for the build status
[17:15:06.917] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:06.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:52:0) after 1m0s
[17:16:06.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:52:0)
[17:16:06.997] Timer tick, asking Buildbucket for the build status
[17:16:07.501] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:07.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:53:0) after 1m0s
[17:17:07.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:53:0)
[17:17:07.528] Timer tick, asking Buildbucket for the build status
[17:17:08.314] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:08.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:54:0) after 1m0s
[17:18:08.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:54:0)
[17:18:08.391] Timer tick, asking Buildbucket for the build status
[17:18:08.508] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:08.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:55:0) after 1m0s
[17:19:08.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:55:0)
[17:19:08.577] Timer tick, asking Buildbucket for the build status
[17:19:08.688] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:56:0) after 1m0s
[17:20:08.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:56:0)
[17:20:08.707] Timer tick, asking Buildbucket for the build status
[17:20:08.792] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:08.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:57:0) after 1m0s
[17:21:08.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:57:0)
[17:21:08.839] Timer tick, asking Buildbucket for the build status
[17:21:08.969] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:08.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:58:0) after 1m0s
[17:22:09.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:58:0)
[17:22:09.030] Timer tick, asking Buildbucket for the build status
[17:22:09.134] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:59:0) after 1m0s
[17:23:09.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:59:0)
[17:23:09.162] Timer tick, asking Buildbucket for the build status
[17:23:09.277] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:09.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:60:0) after 1m0s
[17:24:09.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:60:0)
[17:24:09.273] Timer tick, asking Buildbucket for the build status
[17:24:09.366] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:09.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:61:0) after 1m0s
[17:25:10.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:61:0)
[17:25:10.388] Timer tick, asking Buildbucket for the build status
[17:25:10.623] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:10.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:62:0) after 1m0s
[17:26:10.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:62:0)
[17:26:10.889] Timer tick, asking Buildbucket for the build status
[17:26:11.064] Build 8855033341358729552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:11.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020463403279083136:63:0) after 1m0s
[17:26:39.751] Received PubSub notification, asking Buildbucket for the build status
[17:26:39.856] Build 8855033341358729552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:26:39.856] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613582798892571",
"created_by": "project:chromium",
"created_ts": "1613579153633631",
"id": "8855033341358729552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\"}}",
"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\": \"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\", \"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-46-gttn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45501, \"got_angle_revision\": \"105b8c74e3259d77cd53283bc4a56df06fcbb2a8\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\", \"got_revision_cp\": \"refs/heads/master@{#854797}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"b34b12f070e389b1180a8a4451625cabacbfb29e\", \"got_v8_revision_cp\": \"refs/heads/9.0.200@{#1}\", \"got_webrtc_revision\": \"89c40e246e39372390f0f843545d4e56aa657040\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33286}\", \"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\": \"8eedb06db1f5dc6f6e2ab14fa124f64337bf9684\", \"swarm_hashes_refs/heads/master(at){#854797}_without_patch\": {\"absl_hardening_tests\": \"4400ce81dba656d4aae53cf4a92f97facdd99334\", \"accessibility_unittests\": \"b5d16cbec57c9e51a5b86705556de4ec2c0611b0\", \"angle_unittests\": \"bf14ad55fafef0896ea2cfe2dc41687e15eb238e\", \"app_list_unittests\": \"ab50ba921d181761baeeec117dd07a42635836f8\", \"app_shell_unittests\": \"8073d220f80612e10b8e137fd3f928813bb11c80\", \"ash_unittests\": \"714c3d7c20931ed55ffec165b5eae704aa98d5e9\", \"aura_unittests\": \"09f496eddc959ee3809e3c8d71e167897ec9d0cb\", \"base_unittests\": \"48aad70e92c2e8ac23259c79995afdd4f7e107dd\", \"base_util_unittests\": \"4b519c6dde9a1de11102a50faa4487838ffd2738\", \"blink_common_unittests\": \"d9be02bcf70cea77e6219db4a1936c82e8cc009d\", \"blink_fuzzer_unittests\": \"b5bdaa2726f6d762804af28137516a9b433a3259\", \"blink_heap_unittests\": \"a56cead6c99813ac592bad909763333dc509def2\", \"blink_platform_unittests\": \"7ce7b6c649ef64d0926344b27156c40b3da6a35d\", \"blink_unittests\": \"ddb85ca61f7fab56dce3bbf79ad786106d9f6200\", \"boringssl_crypto_tests\": \"988b545749c604675152ac3a6f22bf8039516e84\", \"boringssl_ssl_tests\": \"4987cd1d2243098d3bc430ef0599cfbecd7ea564\", \"browser_tests\": \"d86cf8a8884b21b4e68fc0cb4043a8621c42629f\", \"capture_unittests\": \"05c26a589c3f566beeea294d8147a724aad1b229\", \"cast_unittests\": \"fd27981524ae7fb96d76fd7967c02ae937eecb00\", \"cc_unittests\": \"cd826daf1d7dc7c6eb6f587bc36e2b4600201483\", \"chrome_app_unittests\": \"871211db211d47a680d0a42bb16e4a33ca8884b1\", \"chromedriver_unittests\": \"2e69220f002716d5e29fa569aed3d88381840273\", \"chromeos_components_unittests\": \"3f790c8f486c6082c93fb01e9163b66b9b90f5d4\", \"chromeos_unittests\": \"78a48d5696329a2214a0cbe94f3d082721d60d00\", \"components_browsertests\": \"bc1309d3985ff4212fbf4816c567100cd13caab2\", \"components_unittests\": \"bf8ffb49ec4060ff5b2fbe245a6d89456ea6ea66\", \"compositor_unittests\": \"a1a79030c66d3557a90696bd562b80942544edd4\", \"content_browsertests\": \"2c43a3108f35a08c084a7905b56becf3811ce8af\", \"content_unittests\": \"a699a954f646100342a13187ee4a632e92931f40\", \"crashpad_tests\": \"5cc174d60ffb1a4a12c72e5333f3e081d693872b\", \"crypto_unittests\": \"041e4bab3380524cf2b63e5182c8553ffedf2fcc\", \"dbus_unittests\": \"f3187ced89b436b2947fe84e2fc2c6d51f6120b0\", \"device_unittests\": \"334b4b697b0f4a42c84edb85dac2cf0f83f5e009\", \"display_unittests\": \"ae72a0f57b8bd6030f8437f725bba6b50752eb48\", \"events_unittests\": \"b30280b1b36290154ff951457a0c5efb7008b097\", \"exo_unittests\": \"7be1d14ef3bfe62f84dcd222e8c5979122f3218d\", \"extensions_browsertests\": \"43f5729f4720943ae6867ceb9c10cbeb3e397faf\", \"extensions_unittests\": \"e43b747e4ff0dc8729c04a89524f5af97ad5cbd2\", \"filesystem_service_unittests\": \"921b182d7ec08bc15bb08048d5a7599317415dec\", \"gcm_unit_tests\": \"4c8389be29d4421a4c1f316359f32b2af70a0d88\", \"gfx_unittests\": \"b3113c524385ab0c52d1c50039b3ea3ac9cec09e\", \"gin_unittests\": \"8b7038bf97930b7817f0692cf0a965819669abd3\", \"gl_unittests_ozone\": \"be2f8bf10b84565646a2b1448bd3bcd46b17537c\", \"google_apis_unittests\": \"89d339e3af3aba88a1026a56a5e7dc8daad7dca0\", \"gpu_unittests\": \"e9c95cadbb6d06dd775f8b8bc48bdd161f63e14a\", \"gwp_asan_unittests\": \"622a7d572b8480cfd2957f191ab91fe647b0bd9e\", \"interactive_ui_tests\": \"0236eb814fd719b08d7f36a97b5bedeb7bdd4226\", \"ipc_tests\": \"8c6ce8e9e9bbcae2fdcd8c834b771ec1b3bb5220\", \"jingle_unittests\": \"d4650f70b74cf6bf051cfcd3a1f23af84cbd8982\", \"keyboard_unittests\": \"86f5448d60dd2747c70f2f2c682c6f29f0b1948a\", \"latency_unittests\": \"bf5e7c0f112efa721634d4494cb58f8219651ed3\", \"libjingle_xmpp_unittests\": \"91de529471c230773f8c582575e40a3d6ca64de8\", \"liburlpattern_unittests\": \"db790508251c2566a2e686b8df1d3d4eb054c8e9\", \"media_blink_unittests\": \"bf7c9f9da93ca7a367db6dc53f488d8bb96da0b5\", \"media_unittests\": \"4c4aca7e3bacccce944aeed3279bf79e5c31bbf9\", \"message_center_unittests\": \"a7a4463d2f930f64b7b40a6e1b796e6939ad93ba\", \"midi_unittests\": \"ad0c07e240bc7164a841ba225a3369279acdbf92\", \"mojo_core_unittests\": \"26269206a33f8de9edd17351e33f2346a82d4794\", \"mojo_unittests\": \"ad0a5277a598b619d777ad2db2cd7714ac91e919\", \"nacl_helper_nonsfi_unittests\": \"1d328783a817d0d72f7bc366cac7f9b1ec2f55d4\", \"nacl_loader_unittests\": \"2f328e15a9faaabe90ee2755a4dd772a11d18a58\", \"native_theme_unittests\": \"f4ab7413da10a58a730cb6f3ca9e7f04bb42820f\", \"net_unittests\": \"0085b8e9d60333475c368644c746627d27a77fbd\", \"ozone_gl_unittests\": \"6c392fd2e71e38ac49948713037253f2cd8de895\", \"ozone_unittests\": \"64a5dad39577992d506221ce078c5c7fcd63408f\", \"ozone_x11_unittests\": \"724107bbc2ac9dc21d6acd35ab0e0ee080d522e8\", \"pdf_unittests\": \"9908746011c15ec890cac013230b12b5a84f8fd5\", \"perfetto_unittests\": \"4351229a7d67b47b96e7d1c46708d85288f100d0\", \"ppapi_unittests\": \"597c784ddbf8f69af03e5fc54f944547eac0681e\", \"printing_unittests\": \"7d4487df6e655d9027bd776708f9d0b832523706\", \"remoting_unittests\": \"42c666431db29dc18f07db41c23b0c5f9b1828ab\", \"sandbox_linux_unittests\": \"5a6bfa14b4b8f42fa4d612eef4e5fe6f8ef5b70a\", \"service_manager_unittests\": \"e235fdf7534658cd4267064b7b7a773b05309252\", \"services_unittests\": \"74bec5ea76a0413d94730d42de4142e7d5f40631\", \"shell_dialogs_unittests\": \"2d659ee1e5ef6a28fc9f8e3e61383c141fd8673f\", \"shell_encryption_unittests\": \"6f3c1e00273f7ad329f4aaad8f4b16880ba79a2b\", \"skia_unittests\": \"038d8d020c312e653f1340764a83a8d61505394d\", \"snapshot_unittests\": \"89374daccce1f2754ea8bf1bf40e66c23400c6b6\", \"sql_unittests\": \"648f025b093ef9aa644968f316d85b4c79e925b5\", \"storage_unittests\": \"7af2bf55b16dc90c3edccd6db9dd7862eab06869\", \"sync_integration_tests\": \"576d151a099f566366c11134ba4f789750ea5c11\", \"traffic_annotation_auditor_unittests\": \"ada033b303b77ca2be0c4bdeb14dd3a26e89ea92\", \"ui_base_unittests\": \"b6ebd7702397fdfea184d2a9a0b15fdb70319017\", \"ui_chromeos_unittests\": \"651c98c1550d46ab986c74ef8f1139563fe28bef\", \"ui_touch_selection_unittests\": \"1bb7bb629ab7d512606da29b5623d7345388d0c0\", \"unit_tests\": \"8d8054d474d9da2bc6d420e3a7599cda932b71df\", \"url_unittests\": \"3bd6817b22ec9f780a57eba1c02244497358dfcd\", \"usage_time_limit_unittests\": \"3faac20ec2749ae5498bafc55f6ed13324701be3\", \"views_unittests\": \"8c3032f571a4dde4992a8bf144facc79a303f744\", \"viz_unittests\": \"fcadde370e237904ed6e5010925f0b942d8d97cd\", \"wayland_client_perftests\": \"1e495aebcc5341f39d9554d35d5bdabddfc65f07\", \"wm_unittests\": \"ab3c92025255883adc74e5bea8ebcd0a31fa36ce\", \"wtf_unittests\": \"87ab6a5c803b19cfa655ef2bdd5d44eafc34c63f\", \"zlib_unittests\": \"cd48f599ca074f7d93f51506b2937444952bc17d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1313787165392eb219c57f145b1b9e0fbbb62d544385caa60e27617303515107_v2\", \"builder_1415e99d1ada386cfb0c84043d2495490d2cdcd28ad7785eb6837a9119e6428f_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-46-gttn\"], \"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\": [\"5700-b36f18b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613579155682593",
"status": "COMPLETED",
"status_changed_ts": "1613582799213527",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45501",
"builder:linux-chromeos-rel",
"buildset:commit/git/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8eedb06db1f5dc6f6e2ab14fa124f64337bf9684",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020463403279083136",
"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/8855033341358729552/+/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:51c9a17bf7535f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613582799218435",
"url": "https://ci.chromium.org/b/8855033341358729552",
"utcnow_ts": "1613582799843411"
}
}
[17:26:39.856] Invocation finished in 1h0m47.437919523s with status SUCCEEDED