[22:50:13.195] New invocation is queued and will start shortly
[22:50:14.327] Starting the invocation (attempt 1)
[22:50:14.382] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:50:14.383] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:50:14.383] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022975937388035648",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a0eca39230c046796f171793ac186036964f01f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022975937388035648",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5a0eca39230c046796f171793ac186036964f01f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a0eca39230c046796f171793ac186036964f01f",
"gitiles_ref:refs/heads/master"
]
}
[22:50:15.502] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611183014518527",
"id": "8857545875326909984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a0eca39230c046796f171793ac186036964f01f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611183015271187",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44776",
"builder:linux-chromeos-rel",
"buildset:commit/git/5a0eca39230c046796f171793ac186036964f01f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a0eca39230c046796f171793ac186036964f01f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022975937388035648",
"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/8857545875326909984/+/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": "1611183015272254",
"url": "https://ci.chromium.org/b/8857545875326909984",
"utcnow_ts": "1611183015488368"
}
}
[22:50:15.502] Task URL: https://ci.chromium.org/b/8857545875326909984
[22:50:15.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:2:0) after 1m0s
[22:50:36.483] Received PubSub notification, asking Buildbucket for the build status
[22:50:36.926] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:15.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:2:0)
[22:51:15.484] Timer tick, asking Buildbucket for the build status
[22:51:15.704] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:15.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:4:0) after 1m0s
[22:52:15.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:4:0)
[22:52:15.761] Timer tick, asking Buildbucket for the build status
[22:52:15.944] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:15.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:5:0) after 1m0s
[22:53:16.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:5:0)
[22:53:16.020] Timer tick, asking Buildbucket for the build status
[22:53:16.145] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:16.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:6:0) after 1m0s
[22:54:16.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:6:0)
[22:54:16.237] Timer tick, asking Buildbucket for the build status
[22:54:16.391] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:16.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:7:0) after 1m0s
[22:55:16.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:7:0)
[22:55:16.486] Timer tick, asking Buildbucket for the build status
[22:55:16.619] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:16.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:8:0) after 1m0s
[22:56:16.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:8:0)
[22:56:16.726] Timer tick, asking Buildbucket for the build status
[22:56:16.843] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:16.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:9:0) after 1m0s
[22:57:16.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:9:0)
[22:57:16.859] Timer tick, asking Buildbucket for the build status
[22:57:16.918] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:16.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:10:0) after 1m0s
[22:58:16.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:10:0)
[22:58:16.937] Timer tick, asking Buildbucket for the build status
[22:58:17.015] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:17.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:11:0) after 1m0s
[22:59:17.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:11:0)
[22:59:17.035] Timer tick, asking Buildbucket for the build status
[22:59:17.111] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:17.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:12:0) after 1m0s
[23:00:17.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:12:0)
[23:00:17.159] Timer tick, asking Buildbucket for the build status
[23:00:17.244] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:17.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:13:0) after 1m0s
[23:01:17.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:13:0)
[23:01:17.365] Timer tick, asking Buildbucket for the build status
[23:01:17.544] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:17.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:14:0) after 1m0s
[23:02:17.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:14:0)
[23:02:17.558] Timer tick, asking Buildbucket for the build status
[23:02:17.642] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:15:0) after 1m0s
[23:03:17.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:15:0)
[23:03:17.759] Timer tick, asking Buildbucket for the build status
[23:03:18.116] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:18.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:16:0) after 1m0s
[23:04:18.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:16:0)
[23:04:18.111] Timer tick, asking Buildbucket for the build status
[23:04:18.355] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:18.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:17:0) after 1m0s
[23:05:18.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:17:0)
[23:05:18.382] Timer tick, asking Buildbucket for the build status
[23:05:18.619] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:18.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:18:0) after 1m0s
[23:06:18.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:18:0)
[23:06:18.675] Timer tick, asking Buildbucket for the build status
[23:06:18.858] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:18.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:19:0) after 1m0s
[23:07:18.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:19:0)
[23:07:18.843] Timer tick, asking Buildbucket for the build status
[23:07:19.007] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:19.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:20:0) after 1m0s
[23:08:19.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:20:0)
[23:08:19.013] Timer tick, asking Buildbucket for the build status
[23:08:19.088] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:19.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:21:0) after 1m0s
[23:09:19.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:21:0)
[23:09:19.101] Timer tick, asking Buildbucket for the build status
[23:09:19.168] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:19.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:22:0) after 1m0s
[23:10:19.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:22:0)
[23:10:19.198] Timer tick, asking Buildbucket for the build status
[23:10:19.298] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:19.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:23:0) after 1m0s
[23:11:19.348] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:23:0)
[23:11:19.348] Timer tick, asking Buildbucket for the build status
[23:11:19.413] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:19.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:24:0) after 1m0s
[23:12:19.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:24:0)
[23:12:19.478] Timer tick, asking Buildbucket for the build status
[23:12:19.541] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:19.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:25:0) after 1m0s
[23:13:19.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:25:0)
[23:13:19.613] Timer tick, asking Buildbucket for the build status
[23:13:19.675] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:19.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:26:0) after 1m0s
[23:14:19.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:26:0)
[23:14:19.708] Timer tick, asking Buildbucket for the build status
[23:14:19.776] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:19.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:27:0) after 1m0s
[23:15:19.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:27:0)
[23:15:19.880] Timer tick, asking Buildbucket for the build status
[23:15:20.053] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:28:0) after 1m0s
[23:16:20.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:28:0)
[23:16:20.125] Timer tick, asking Buildbucket for the build status
[23:16:20.217] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:20.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:29:0) after 1m0s
[23:17:20.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:29:0)
[23:17:20.267] Timer tick, asking Buildbucket for the build status
[23:17:20.343] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:20.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:30:0) after 1m0s
[23:18:20.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:30:0)
[23:18:20.366] Timer tick, asking Buildbucket for the build status
[23:18:20.463] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:20.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:31:0) after 1m0s
[23:19:20.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:31:0)
[23:19:20.505] Timer tick, asking Buildbucket for the build status
[23:19:20.692] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:20.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:32:0) after 1m0s
[23:20:20.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:32:0)
[23:20:20.730] Timer tick, asking Buildbucket for the build status
[23:20:20.909] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:20.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:33:0) after 1m0s
[23:21:20.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:33:0)
[23:21:20.922] Timer tick, asking Buildbucket for the build status
[23:21:20.995] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:20.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:34:0) after 1m0s
[23:22:21.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:34:0)
[23:22:21.026] Timer tick, asking Buildbucket for the build status
[23:22:21.520] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:21.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:35:0) after 1m0s
[23:23:21.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:35:0)
[23:23:21.522] Timer tick, asking Buildbucket for the build status
[23:23:21.797] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:21.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:36:0) after 1m0s
[23:24:21.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:36:0)
[23:24:21.868] Timer tick, asking Buildbucket for the build status
[23:24:21.934] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:37:0) after 1m0s
[23:25:21.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:37:0)
[23:25:21.981] Timer tick, asking Buildbucket for the build status
[23:25:22.395] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:22.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:38:0) after 1m0s
[23:26:22.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:38:0)
[23:26:22.459] Timer tick, asking Buildbucket for the build status
[23:26:22.514] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:22.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:39:0) after 1m0s
[23:27:22.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:39:0)
[23:27:22.533] Timer tick, asking Buildbucket for the build status
[23:27:22.937] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:22.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:40:0) after 1m0s
[23:28:23.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:40:0)
[23:28:23.023] Timer tick, asking Buildbucket for the build status
[23:28:23.415] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:41:0) after 1m0s
[23:29:23.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:41:0)
[23:29:23.454] Timer tick, asking Buildbucket for the build status
[23:29:23.820] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:23.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:42:0) after 1m0s
[23:30:24.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:42:0)
[23:30:24.004] Timer tick, asking Buildbucket for the build status
[23:30:24.138] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:24.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:43:0) after 1m0s
[23:31:24.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:43:0)
[23:31:24.446] Timer tick, asking Buildbucket for the build status
[23:31:24.551] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:24.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:44:0) after 1m0s
[23:32:24.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:44:0)
[23:32:24.627] Timer tick, asking Buildbucket for the build status
[23:32:24.739] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:24.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:45:0) after 1m0s
[23:33:24.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:45:0)
[23:33:24.885] Timer tick, asking Buildbucket for the build status
[23:33:25.039] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:46:0) after 1m0s
[23:34:25.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:46:0)
[23:34:25.144] Timer tick, asking Buildbucket for the build status
[23:34:25.260] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:25.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:47:0) after 1m0s
[23:35:25.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:47:0)
[23:35:25.480] Timer tick, asking Buildbucket for the build status
[23:35:25.672] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:25.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:48:0) after 1m0s
[23:36:25.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:48:0)
[23:36:25.832] Timer tick, asking Buildbucket for the build status
[23:36:25.966] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:25.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:49:0) after 1m0s
[23:37:25.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:49:0)
[23:37:25.995] Timer tick, asking Buildbucket for the build status
[23:37:26.108] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:26.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:50:0) after 1m0s
[23:38:26.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:50:0)
[23:38:26.132] Timer tick, asking Buildbucket for the build status
[23:38:26.261] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:26.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:51:0) after 1m0s
[23:39:26.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:51:0)
[23:39:26.315] Timer tick, asking Buildbucket for the build status
[23:39:26.517] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:26.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:52:0) after 1m0s
[23:40:26.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:52:0)
[23:40:26.548] Timer tick, asking Buildbucket for the build status
[23:40:26.766] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:26.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:53:0) after 1m0s
[23:41:26.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:53:0)
[23:41:26.766] Timer tick, asking Buildbucket for the build status
[23:41:26.909] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:26.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:54:0) after 1m0s
[23:42:27.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:54:0)
[23:42:27.295] Timer tick, asking Buildbucket for the build status
[23:42:27.427] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:27.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:55:0) after 1m0s
[23:43:27.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:55:0)
[23:43:27.632] Timer tick, asking Buildbucket for the build status
[23:43:27.903] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:27.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:56:0) after 1m0s
[23:44:28.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:56:0)
[23:44:28.076] Timer tick, asking Buildbucket for the build status
[23:44:28.645] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:28.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:57:0) after 1m0s
[23:45:28.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:57:0)
[23:45:28.799] Timer tick, asking Buildbucket for the build status
[23:45:28.895] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:28.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:58:0) after 1m0s
[23:46:29.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:58:0)
[23:46:29.069] Timer tick, asking Buildbucket for the build status
[23:46:29.197] Build 8857545875326909984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022975937388035648:59:0) after 1m0s
[23:46:53.495] Received PubSub notification, asking Buildbucket for the build status
[23:46:53.567] Build 8857545875326909984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:46:53.567] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611186412220334",
"created_by": "project:chromium",
"created_ts": "1611183014518527",
"id": "8857545875326909984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a0eca39230c046796f171793ac186036964f01f\"}}",
"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\": \"5a0eca39230c046796f171793ac186036964f01f\", \"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-65-yzph\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44776, \"got_angle_revision\": \"ea71c6b63417af438afaf26a886b03621d85f14f\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5a0eca39230c046796f171793ac186036964f01f\", \"got_revision_cp\": \"refs/heads/master@{#845382}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"812c73cdc25ade1c08750873f0c1e8f8def206dc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33043}\", \"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\": \"5a0eca39230c046796f171793ac186036964f01f\", \"swarm_hashes_refs/heads/master(at){#845382}_without_patch\": {\"absl_hardening_tests\": \"39a9ad978d1ee8583c55041a581691ed9e6b7a48\", \"accessibility_unittests\": \"db51c95fb648ed0edb8d0f7fd1a48749e93244d5\", \"angle_unittests\": \"6a8d7868e63c77f591222b80ac39d416dbbf86dc\", \"app_list_unittests\": \"0c4a067b04da1595f94383a765dcd793333f9a00\", \"app_shell_unittests\": \"aacf0dd933fabbfa807553b5cdb60c73dce8eee8\", \"ash_unittests\": \"29e2c3398ea0c03d68c5c92ea241b46c7991b1db\", \"aura_unittests\": \"bb22b2c2ef137244664c58e936ee439796304cd5\", \"base_unittests\": \"3a9545e638b44dd0189de67f3810d0d9aea35d11\", \"base_util_unittests\": \"244330cf4f0460d70ebd4a5c17139987b8018a1d\", \"blink_common_unittests\": \"8c48a37e00c15a13509dad04de41068085e7df76\", \"blink_fuzzer_unittests\": \"49553f6c15ac2c75b9fe93110d567c359cb4ff41\", \"blink_heap_unittests\": \"bf52707b778e8c286b3b7a4f516bfbf7494367b5\", \"blink_platform_unittests\": \"7968dbecd87d7decaa7a1ff1c0359d84f57a1b9b\", \"blink_unittests\": \"8eece86ff395a5c8073e60196d2aa4eba21a49a6\", \"boringssl_crypto_tests\": \"23e71fe9df9b8501f2752a0243736db6fb60deb9\", \"boringssl_ssl_tests\": \"051e74b38d79dbb5b2f659552773ee24864f0dd5\", \"browser_tests\": \"fdfb43707b564766f37e65b3d9a84538927d781a\", \"capture_unittests\": \"fb045403bead561548c1a303fd0ab4b14cfa3070\", \"cast_unittests\": \"f313c6b62ed992ace97c0e317848f223bc67eb7f\", \"cc_unittests\": \"62ac167c485f6853f6a2c6a29c3a9c4504a53eb6\", \"chrome_app_unittests\": \"c7abc7b32c28302c3fa200431f93b07a3acf96f3\", \"chromedriver_unittests\": \"9422c0f1049902f2d5bfcf7ea049556f4c35e749\", \"chromeos_components_unittests\": \"3f69ee4aa0ece550f7ed40a9ebaf82066f8517f5\", \"chromeos_unittests\": \"d4250adf92b3ead66018e8f9c3a5250b450eae36\", \"components_browsertests\": \"30010073a8cd2d1f75d4c33d03a5fa0ff6edb688\", \"components_unittests\": \"23fb8874456001f21a9844fac87c54d00c5b15c2\", \"compositor_unittests\": \"56e3eb50fefcf648544809385809018c2e367d66\", \"content_browsertests\": \"794f24525698017fd7ec892a2a1f055d3cb52c3c\", \"content_unittests\": \"329383588634a6c679365bb5758816e5b5ed1436\", \"crashpad_tests\": \"f243ea2e851509b852e45c81695dd8ba7da53b56\", \"crypto_unittests\": \"728a70a8e91f911cdda7361f067b8e3aa4678986\", \"dbus_unittests\": \"d9235e0c1bb5bd6e96a6668fc886c69f5530674e\", \"device_unittests\": \"4bbf424b77d5f63fab1f59bee834bd7f4634af6a\", \"display_unittests\": \"a8be936df1f9b75b79008301c76b3d1a4ebef3e6\", \"events_unittests\": \"a9d0f70898a9163cafc2dd65c33fb7a502db9bb3\", \"exo_unittests\": \"5731ad59e9661ae9a51607f980cef7dfd48e873d\", \"extensions_browsertests\": \"a26184a6ca7a958e1883d74ed1269537cf074302\", \"extensions_unittests\": \"a91545d518dfd528c83697e54c4f4ecc6fb0fc81\", \"filesystem_service_unittests\": \"98b7ac2be6606684f98cb829094c5f1c7a434768\", \"gcm_unit_tests\": \"8e6b898f9a54bc1beaa3a2c7aba0ac0fd897fd12\", \"gfx_unittests\": \"cc8ad6a1d052daac736bd262d591ba9bf5498176\", \"gin_unittests\": \"555ada4edba78533d0da242e6101cdd26dfd8f93\", \"gl_unittests_ozone\": \"ab08745126b55afe8009e334a1e14c43d4448289\", \"google_apis_unittests\": \"1dbd734224a873f1cc3447e609d77a3e4b2f465a\", \"gpu_unittests\": \"e4ad3f302195ccace150404de8d641f750fbe6e5\", \"gwp_asan_unittests\": \"822cc5a7d1e79601c087333ac76bcdf37029a2b0\", \"interactive_ui_tests\": \"66a6532c9318b83683c2f26b2dcba10d74e48526\", \"ipc_tests\": \"0bcf942c383a4d41d2040159c95b329426a582bd\", \"jingle_unittests\": \"b960e3ede9d264b17c755bf544d3c38228116265\", \"keyboard_unittests\": \"a52bbf9f5fcc435bca5354848d2a67243fea7d8e\", \"latency_unittests\": \"d9eb48b4ce01414c789313da9aff211725656655\", \"libjingle_xmpp_unittests\": \"1ce110ed923a0b9f064317b60950dee215509f09\", \"liburlpattern_unittests\": \"8c58b74104dc019917bde000cd40310954a87629\", \"media_blink_unittests\": \"01a08572001c9fe766043941c032c7839b9bf456\", \"media_unittests\": \"891fa1b3a063659040010040d1fc81bf44e92f4a\", \"message_center_unittests\": \"382b4bf3e655d0ab855c48cbe57978d4bf3ab006\", \"midi_unittests\": \"044586b2e0549e56e0fbdcf791d099db578a9aec\", \"mojo_core_unittests\": \"5699b902e3eaaa310a0cfcabecc35f545de76c9f\", \"mojo_unittests\": \"9d623786cae7f5413a6191ea439c19d955976d84\", \"nacl_helper_nonsfi_unittests\": \"193e237b4987101df9864480ff82fb6daf77dd28\", \"nacl_loader_unittests\": \"4820ba94eb5a507b3e96150bd6d65dfcc440ada2\", \"native_theme_unittests\": \"4e9ee7fa24f4074f838d8f4691d177b3d095d234\", \"net_unittests\": \"9e7ce1a89bd214afe63e4fb1148dd53bfb9a412b\", \"ozone_gl_unittests\": \"1f42410afbecfd672ec3abc7374c9a1aa0cc0219\", \"ozone_unittests\": \"fdbeb07ada79c15cd512ba0e00eaf0c7f92a6e40\", \"ozone_x11_unittests\": \"2722fa42fe42b87aacfb842e7faa77be4cb093ca\", \"pdf_unittests\": \"4443eb8b2ca8b24fb4b86c0e28f4534265f6932c\", \"perfetto_unittests\": \"a775cf6e89377a58e304b2ff6b4b8c3f0e3db803\", \"ppapi_unittests\": \"1359b9eb3926aa1298985837f86a0e175b666367\", \"printing_unittests\": \"48cde76e9009cd030f5fd60d2c24a480cef5a715\", \"remoting_unittests\": \"829f751a78f255606d8f71c419c1c967d90ab4ed\", \"sandbox_linux_unittests\": \"f01291b519d1f5788356ecbc160e702bb84a8e37\", \"service_manager_unittests\": \"914be5f61a2bf4de1077a0a8fb6cd1329744d67f\", \"services_unittests\": \"2b14c607757f67df9201b8a89969d43a85927990\", \"shell_dialogs_unittests\": \"596dfce9fa27defe72919b97b642e0186436de32\", \"shell_encryption_unittests\": \"98692c9ce9dc40aa8fb40b6a1ee65dd6650b6043\", \"skia_unittests\": \"fb9ab6450352ff426d766405e89a60c821d5d0b8\", \"snapshot_unittests\": \"e46a3415d146c0d84fbae8473a70ced2ffb20bc4\", \"sql_unittests\": \"324ab32efdd7a7e5a0d1a8c78ea5e2acb6d953b9\", \"storage_unittests\": \"1a95060b40a303660113fc9c53fcba2af0652d26\", \"sync_integration_tests\": \"78868272dc5f377f4c90a50a750dc3f1e99dae3e\", \"traffic_annotation_auditor_unittests\": \"ffeb8528d6e97007399bc89b2d1de0e623b60233\", \"ui_base_unittests\": \"97469c3203b390161698090e188cbf2f91a1821a\", \"ui_chromeos_unittests\": \"50be4ef13073cae5b9e688affc864ec4899673cc\", \"ui_touch_selection_unittests\": \"86518f21ea6c85dd4a2fb10a19da315ce92caeec\", \"unit_tests\": \"6d99201fa31e2a2718b4e1f59eb237415734e11a\", \"url_unittests\": \"16290403628a89894149515ff8b0c2067bb81cdd\", \"usage_time_limit_unittests\": \"e1bbeea932b58839ef9eb7c7a60088bd42c92678\", \"views_unittests\": \"1a5d9cbbc797c69e69f113072b3bf226b9da657f\", \"viz_unittests\": \"4bcc60ebceb4e5fee782fce24c42ef41c54d59b5\", \"wayland_client_perftests\": \"fcb6205ffaa399c2efa3858f15443a937a6b53a2\", \"wm_unittests\": \"56d295c0d526b1505c27fcd1e02d30940c615375\", \"wtf_unittests\": \"fe9b15c8a29f90b867f5ca4038a25f00633e61e5\", \"zlib_unittests\": \"544d6db82a6d267d44744c6e8780ea8cf3f760d6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_6012964051d5021e7307eb12ad598b318c657120f21d3654d81218fef56916e6_v2\", \"builder_8f1f1a811acb8ac7cee91f001f34cb98db55e889611ed2a31914ce7c854c1541_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_v2\", \"builder_f5174a4d80cdc4c8aa1989fa45d69979d28910f768af140dd646791c34143bfe_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-65-yzph\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611183035722177",
"status": "COMPLETED",
"status_changed_ts": "1611186412911319",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44776",
"builder:linux-chromeos-rel",
"buildset:commit/git/5a0eca39230c046796f171793ac186036964f01f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a0eca39230c046796f171793ac186036964f01f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022975937388035648",
"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/8857545875326909984/+/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:513acf52669f0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611186412915684",
"url": "https://ci.chromium.org/b/8857545875326909984",
"utcnow_ts": "1611186413553523"
}
}
[23:46:53.567] Invocation finished in 56m40.39852615s with status SUCCEEDED