[15:12:28.648] New invocation is queued and will start shortly
[15:12:29.749] Starting the invocation (attempt 1)
[15:12:29.811] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:12:29.812] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:12:29.812] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022461154251620496",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"49c33167881729cccbc3f597d783ae2cc5ae2ffe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022461154251620496",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"gitiles_ref:refs/heads/master"
]
}
[15:12:30.526] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611673949943170",
"id": "8857031092222484112",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49c33167881729cccbc3f597d783ae2cc5ae2ffe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611673950400116",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44918",
"builder:linux-chromeos-rel",
"buildset:commit/git/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022461154251620496",
"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/8857031092222484112/+/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": "1611673950400224",
"url": "https://ci.chromium.org/b/8857031092222484112",
"utcnow_ts": "1611673950518547"
}
}
[15:12:30.526] Task URL: https://ci.chromium.org/b/8857031092222484112
[15:12:30.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:2:0) after 1m0s
[15:13:30.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:2:0)
[15:13:30.803] Timer tick, asking Buildbucket for the build status
[15:13:30.968] Build 8857031092222484112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:30.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:3:0) after 1m0s
[15:13:37.739] Received PubSub notification, asking Buildbucket for the build status
[15:13:37.966] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:31.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:3:0)
[15:14:31.268] Timer tick, asking Buildbucket for the build status
[15:14:31.407] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:31.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:5:0) after 1m0s
[15:15:31.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:5:0)
[15:15:31.770] Timer tick, asking Buildbucket for the build status
[15:15:31.849] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:31.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:6:0) after 1m0s
[15:16:32.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:6:0)
[15:16:32.315] Timer tick, asking Buildbucket for the build status
[15:16:32.375] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:32.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:7:0) after 1m0s
[15:17:32.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:7:0)
[15:17:32.494] Timer tick, asking Buildbucket for the build status
[15:17:32.578] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:32.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:8:0) after 1m0s
[15:18:32.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:8:0)
[15:18:32.730] Timer tick, asking Buildbucket for the build status
[15:18:33.262] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:33.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:9:0) after 1m0s
[15:19:34.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:9:0)
[15:19:34.079] Timer tick, asking Buildbucket for the build status
[15:19:34.271] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:34.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:10:0) after 1m0s
[15:20:34.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:10:0)
[15:20:34.359] Timer tick, asking Buildbucket for the build status
[15:20:34.711] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:34.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:11:0) after 1m0s
[15:21:34.934] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:11:0)
[15:21:34.934] Timer tick, asking Buildbucket for the build status
[15:21:35.279] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:35.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:12:0) after 1m0s
[15:22:35.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:12:0)
[15:22:35.726] Timer tick, asking Buildbucket for the build status
[15:22:35.785] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:35.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:13:0) after 1m0s
[15:23:36.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:13:0)
[15:23:36.017] Timer tick, asking Buildbucket for the build status
[15:23:36.904] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:36.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:14:0) after 1m0s
[15:24:37.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:14:0)
[15:24:37.081] Timer tick, asking Buildbucket for the build status
[15:24:37.337] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:37.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:15:0) after 1m0s
[15:25:37.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:15:0)
[15:25:37.524] Timer tick, asking Buildbucket for the build status
[15:25:37.903] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:37.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:16:0) after 1m0s
[15:26:37.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:16:0)
[15:26:37.932] Timer tick, asking Buildbucket for the build status
[15:26:38.001] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:38.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:17:0) after 1m0s
[15:27:38.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:17:0)
[15:27:38.214] Timer tick, asking Buildbucket for the build status
[15:27:38.309] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:38.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:18:0) after 1m0s
[15:28:38.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:18:0)
[15:28:38.851] Timer tick, asking Buildbucket for the build status
[15:28:38.944] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:38.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:19:0) after 1m0s
[15:29:39.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:19:0)
[15:29:39.198] Timer tick, asking Buildbucket for the build status
[15:29:39.273] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:39.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:20:0) after 1m0s
[15:30:39.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:20:0)
[15:30:39.526] Timer tick, asking Buildbucket for the build status
[15:30:39.762] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:39.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:21:0) after 1m0s
[15:31:39.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:21:0)
[15:31:39.968] Timer tick, asking Buildbucket for the build status
[15:31:40.183] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:40.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:22:0) after 1m0s
[15:32:40.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:22:0)
[15:32:40.306] Timer tick, asking Buildbucket for the build status
[15:32:40.885] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:40.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:23:0) after 1m0s
[15:33:40.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:23:0)
[15:33:40.985] Timer tick, asking Buildbucket for the build status
[15:33:41.065] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:24:0) after 1m0s
[15:34:41.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:24:0)
[15:34:41.124] Timer tick, asking Buildbucket for the build status
[15:34:41.187] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:41.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:25:0) after 1m0s
[15:35:41.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:25:0)
[15:35:41.298] Timer tick, asking Buildbucket for the build status
[15:35:41.386] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:41.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:26:0) after 1m0s
[15:36:42.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:26:0)
[15:36:42.143] Timer tick, asking Buildbucket for the build status
[15:36:42.236] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:42.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:27:0) after 1m0s
[15:37:42.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:27:0)
[15:37:42.693] Timer tick, asking Buildbucket for the build status
[15:37:42.920] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:42.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:28:0) after 1m0s
[15:38:43.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:28:0)
[15:38:43.104] Timer tick, asking Buildbucket for the build status
[15:38:43.184] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:43.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:29:0) after 1m0s
[15:39:43.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:29:0)
[15:39:43.234] Timer tick, asking Buildbucket for the build status
[15:39:43.317] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:30:0) after 1m0s
[15:40:43.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:30:0)
[15:40:43.349] Timer tick, asking Buildbucket for the build status
[15:40:43.612] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:43.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:31:0) after 1m0s
[15:41:43.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:31:0)
[15:41:43.631] Timer tick, asking Buildbucket for the build status
[15:41:45.116] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:32:0) after 1m0s
[15:42:45.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:32:0)
[15:42:45.202] Timer tick, asking Buildbucket for the build status
[15:42:45.340] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:45.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:33:0) after 1m0s
[15:43:45.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:33:0)
[15:43:45.380] Timer tick, asking Buildbucket for the build status
[15:43:45.485] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:45.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:34:0) after 1m0s
[15:44:45.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:34:0)
[15:44:45.514] Timer tick, asking Buildbucket for the build status
[15:44:45.600] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:45.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:35:0) after 1m0s
[15:45:45.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:35:0)
[15:45:45.636] Timer tick, asking Buildbucket for the build status
[15:45:45.711] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:45.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:36:0) after 1m0s
[15:46:45.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:36:0)
[15:46:45.769] Timer tick, asking Buildbucket for the build status
[15:46:45.852] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:45.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:37:0) after 1m0s
[15:47:45.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:37:0)
[15:47:45.963] Timer tick, asking Buildbucket for the build status
[15:47:46.092] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:46.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:38:0) after 1m0s
[15:48:46.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:38:0)
[15:48:46.159] Timer tick, asking Buildbucket for the build status
[15:48:46.854] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:46.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:39:0) after 1m0s
[15:49:47.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:39:0)
[15:49:47.034] Timer tick, asking Buildbucket for the build status
[15:49:47.158] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:40:0) after 1m0s
[15:50:47.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:40:0)
[15:50:47.270] Timer tick, asking Buildbucket for the build status
[15:50:47.415] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:47.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:41:0) after 1m0s
[15:51:47.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:41:0)
[15:51:47.495] Timer tick, asking Buildbucket for the build status
[15:51:47.705] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:42:0) after 1m0s
[15:52:47.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:42:0)
[15:52:47.777] Timer tick, asking Buildbucket for the build status
[15:52:47.985] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:47.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:43:0) after 1m0s
[15:53:48.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:43:0)
[15:53:48.056] Timer tick, asking Buildbucket for the build status
[15:53:48.451] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:48.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:44:0) after 1m0s
[15:54:48.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:44:0)
[15:54:48.551] Timer tick, asking Buildbucket for the build status
[15:54:48.678] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:48.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:45:0) after 1m0s
[15:55:48.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:45:0)
[15:55:48.728] Timer tick, asking Buildbucket for the build status
[15:55:48.955] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:48.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:46:0) after 1m0s
[15:56:48.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:46:0)
[15:56:48.970] Timer tick, asking Buildbucket for the build status
[15:56:49.122] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:49.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:47:0) after 1m0s
[15:57:49.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:47:0)
[15:57:49.138] Timer tick, asking Buildbucket for the build status
[15:57:49.279] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:49.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:48:0) after 1m0s
[15:58:49.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:48:0)
[15:58:49.429] Timer tick, asking Buildbucket for the build status
[15:58:49.574] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:49.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:49:0) after 1m0s
[15:59:49.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:49:0)
[15:59:49.804] Timer tick, asking Buildbucket for the build status
[15:59:49.979] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:49.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:50:0) after 1m0s
[16:00:50.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:50:0)
[16:00:50.211] Timer tick, asking Buildbucket for the build status
[16:00:50.619] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:50.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:51:0) after 1m0s
[16:01:50.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:51:0)
[16:01:50.669] Timer tick, asking Buildbucket for the build status
[16:01:50.925] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:50.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:52:0) after 1m0s
[16:02:51.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:52:0)
[16:02:51.035] Timer tick, asking Buildbucket for the build status
[16:02:51.336] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:51.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:53:0) after 1m0s
[16:03:51.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:53:0)
[16:03:51.498] Timer tick, asking Buildbucket for the build status
[16:03:51.915] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:51.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:54:0) after 1m0s
[16:04:52.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:54:0)
[16:04:52.025] Timer tick, asking Buildbucket for the build status
[16:04:52.118] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:52.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:55:0) after 1m0s
[16:05:52.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:55:0)
[16:05:52.332] Timer tick, asking Buildbucket for the build status
[16:05:52.441] Build 8857031092222484112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:52.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022461154251620496:56:0) after 1m0s
[16:06:39.456] Received PubSub notification, asking Buildbucket for the build status
[16:06:39.742] Build 8857031092222484112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:06:39.742] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611677196578412",
"created_by": "project:chromium",
"created_ts": "1611673949943170",
"id": "8857031092222484112",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49c33167881729cccbc3f597d783ae2cc5ae2ffe\"}}",
"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\": \"49c33167881729cccbc3f597d783ae2cc5ae2ffe\", \"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-120-8ubm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44918, \"got_angle_revision\": \"9bbcd86ae719866273598c988709c7ec3d462e1d\", \"got_dawn_revision\": \"e0feccacafb0643fbaef8f807a9b76f1b9a97828\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"49c33167881729cccbc3f597d783ae2cc5ae2ffe\", \"got_revision_cp\": \"refs/heads/master@{#847168}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"ea1f7d10db1f22f3327f4c0bc690695bc1a73aa0\", \"got_v8_revision\": \"cb0390ea3f974ec4702166fb6ce4455c408b9bdf\", \"got_v8_revision_cp\": \"refs/heads/9.0.70@{#1}\", \"got_webrtc_revision\": \"4f3a2eba6b989f28045be92615bd3fb214a359cc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33071}\", \"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\": \"49c33167881729cccbc3f597d783ae2cc5ae2ffe\", \"swarm_hashes_refs/heads/master(at){#847168}_without_patch\": {\"absl_hardening_tests\": \"be2e82ec65b3d8c9f04f45937cd66f4cafa5c3ed\", \"accessibility_unittests\": \"054d4f960cd43ee51417354a5dafdba7ff8214e3\", \"angle_unittests\": \"e70b1c18c21d036effcb19c499942a1bf14d3010\", \"app_list_unittests\": \"92237dd3a5c53bbb6b7842d7cbec83667736c72c\", \"app_shell_unittests\": \"45ec49709adcbcab69025952a3692afdc804ff88\", \"ash_unittests\": \"da6240b9b740e483b768e8a99323d0f78bd6f256\", \"aura_unittests\": \"3e2a5cbb587453d2cafc625050c062e49927e2a2\", \"base_unittests\": \"7998ba67b06afacfe62362925c09d20d58d4e97d\", \"base_util_unittests\": \"1e7cfb41dee7dcf17e3c38f0ab24ddffdceef401\", \"blink_common_unittests\": \"386f61d3a58ca4f35ba41862afe7fcb81986bc25\", \"blink_fuzzer_unittests\": \"f87833481b399d6559b079aabea32c0a47c653b6\", \"blink_heap_unittests\": \"f744839944245e9570067b898b6ba3964b04dc90\", \"blink_platform_unittests\": \"8991f8ca05ca84c8dcc331fbb4dea41d9b2368cc\", \"blink_unittests\": \"4908e39afa610c0aaf6b004dc6a8605d81bf56c3\", \"boringssl_crypto_tests\": \"b681ccff43b7e5bb5fc7b012c1217e8cc47ca312\", \"boringssl_ssl_tests\": \"69749ddff366d19f621bd87a7cc7a6f7ef368f63\", \"browser_tests\": \"4bfdc4cc8fcd3185df159d5579963eecedddc4cb\", \"capture_unittests\": \"14afcf9d0da16d6355d13cfccfb717539ff19ffd\", \"cast_unittests\": \"39db47c6f1317a92bfd64cd3917414fc30cc71a3\", \"cc_unittests\": \"90c89322100c0859362f36ef26f65c47d4064309\", \"chrome_app_unittests\": \"b7e425ecce515498b27d40c2f0c27c6b6f25d5e0\", \"chromedriver_unittests\": \"872157964d67071d8d4b305b34334b09da0542b0\", \"chromeos_components_unittests\": \"3dc81d22d333065d0c0c2c95c58eac0271258f56\", \"chromeos_unittests\": \"d77375be12bedab96ac4de6c7e68278ccdd1ff42\", \"components_browsertests\": \"6ce79677ce0029fc7d4ccd6b784db84238a362c2\", \"components_unittests\": \"dd1e60aa8c3aeddbb02c4860dafaef90866aa590\", \"compositor_unittests\": \"e198771b12c9c330c36817ea152a36d715bd7c6f\", \"content_browsertests\": \"a54014787de6f3be4594f8823cbec2199c959251\", \"content_unittests\": \"53fe669f22db9e3bec61828367859e07f9bccccf\", \"crashpad_tests\": \"dc74d29dc13d5d2164c2c3ba47421d0f977721ab\", \"crypto_unittests\": \"96ff67c1522a9a7411ee3757c815d471975f4cab\", \"dbus_unittests\": \"70764d5c32f2ae64f9f817d8c7d055ec73ca940d\", \"device_unittests\": \"d2b1503220e92aa6b8df2be059ef6a64f2b9fdb1\", \"display_unittests\": \"73eb861003e60d4a45863711c814219906adbc70\", \"events_unittests\": \"5e60d078ac2a1a7cbe7e279cf998f0698e073531\", \"exo_unittests\": \"85a2c3c93aac6e70196d64d072189d254bc205a2\", \"extensions_browsertests\": \"56371ffc65890d8da53d11200dcdd1f44cf0aa05\", \"extensions_unittests\": \"b3812e66c6737b6fe7856f2b812afbf820d5d64b\", \"filesystem_service_unittests\": \"1798e6d9e5daf1c3cdf81df58b2a2a9a685c7f08\", \"gcm_unit_tests\": \"46307d6958446504441f2ed0f17e42ff9c44854c\", \"gfx_unittests\": \"fb851a8716a717301cab3187c8a50317b2375881\", \"gin_unittests\": \"b003641cc932208efe7a612b71f9afd5d2b7ddd3\", \"gl_unittests_ozone\": \"918ad2109a56828623aa658fedf7062168f4eb0d\", \"google_apis_unittests\": \"19b9abf141ff388a49f577b0ac620f7694163b33\", \"gpu_unittests\": \"979846d2026b2eaffed927c99f637082d0b35481\", \"gwp_asan_unittests\": \"47866bbfe1a2bd92fc91b2965ee3272f6dd2ca3b\", \"interactive_ui_tests\": \"e6f11b6e5702a7ffd99c260a536b1c684bbc49a4\", \"ipc_tests\": \"43305ba9cb6d4927c04b4a8e612a71136f00e0ec\", \"jingle_unittests\": \"c19ed80903011ad63a5b2b5b0ff9154c11e6043a\", \"keyboard_unittests\": \"8a4d0d8d3275c08045243aa0a35ad805414673d7\", \"latency_unittests\": \"17da9285a95cfbe339d741bfa20ddc61cba6187a\", \"libjingle_xmpp_unittests\": \"7cc1cec297d6b5c40512ba865cf8aca981780c05\", \"liburlpattern_unittests\": \"8fce5cf570f36134a3b9aef87df500c3176a3093\", \"media_blink_unittests\": \"b86722c95ec8256f89e637401c546e4c24b1525b\", \"media_unittests\": \"13dcb32d5279c094d91d5c8a461d55fb97d19af1\", \"message_center_unittests\": \"713f568ad66934bb85c8737b7230bd2989835820\", \"midi_unittests\": \"8f5605ca1341130c52f08753f1d362d7ee928e1d\", \"mojo_core_unittests\": \"031e6d8acf82715e65b4ba897e045c99528fb19a\", \"mojo_unittests\": \"027f4d5557744cd305ec2c74a133ed18e19c795b\", \"nacl_helper_nonsfi_unittests\": \"7af2965068f92d4010663ef459ea89bea2adaece\", \"nacl_loader_unittests\": \"b9be8e4e06d591aabe7447f666f97f49b83742ff\", \"native_theme_unittests\": \"c1f45f7eb23aafed857bdbce8e504def1e5fa303\", \"net_unittests\": \"fbba62417039c477cafaeaa91538fd837b426dc3\", \"ozone_gl_unittests\": \"fe8314319637a90e75f517e1d174c3056af1f95b\", \"ozone_unittests\": \"537d0d9b3b903037a6461ea8c567a2c264f11cb4\", \"ozone_x11_unittests\": \"86c65d5468347077aa34ee04ef02e1802f9f8327\", \"pdf_unittests\": \"641fa271b2493f6cfa2e1dd897e9c0ec0cd2cd30\", \"perfetto_unittests\": \"bd370282f946a36451c18f460d745cbcc1e4ebff\", \"ppapi_unittests\": \"edd309ee0f75335c5e542706dff3fcb39d6d1f2e\", \"printing_unittests\": \"773688d368ecac2917ba32f394f741548f2662a0\", \"remoting_unittests\": \"2597579d07f375f41beb70370a4e804070321115\", \"sandbox_linux_unittests\": \"ef42a2ddff480578b415765836ee0f2834e58f01\", \"service_manager_unittests\": \"a11e7aab234791077feba3a00cb7038287c66c97\", \"services_unittests\": \"bc8e6b7eef1a236431bd6a7120e658fa0049e56a\", \"shell_dialogs_unittests\": \"7e214d02f62c0ff8d7ba5577561b8a29e3786985\", \"shell_encryption_unittests\": \"1d970bedcd4a7cacbdef8cf26178e266a204dd05\", \"skia_unittests\": \"3d094323ceb7381dd6173bb899fcdd93f28f8697\", \"snapshot_unittests\": \"309477e3f1c7fa920081871134056877fc181134\", \"sql_unittests\": \"ddacad912f24a1437db91cb856b51fb366fe21fb\", \"storage_unittests\": \"15ea6a88d2b7a184e2e9112cc6827146d7dfaefc\", \"sync_integration_tests\": \"95ee701f049297ead2a9944dea50133063d460e2\", \"traffic_annotation_auditor_unittests\": \"a0a06945c6959dd13a29b924b762a03e0842f14b\", \"ui_base_unittests\": \"37ebae032a03527e22c728468cb6865ff3cf9116\", \"ui_chromeos_unittests\": \"67a901a6d87374c9e0cb42782bc8a9086f24f412\", \"ui_touch_selection_unittests\": \"f4044e629841d66eb54865ea1fe48fec1bed37bb\", \"unit_tests\": \"3e50b6415be0a51963d90006c90abeb423070e57\", \"url_unittests\": \"a524e0024ee7addcd953038240ed87b5ff052cee\", \"usage_time_limit_unittests\": \"b9f359c15f56eda0953ba9c3680a83f53ac00a0f\", \"views_unittests\": \"2a7853f944a296dcc7ebb96fdaef324fa90e408e\", \"viz_unittests\": \"bc3a62f1d93f78ab52f62c66a4233a03f72b26f2\", \"wayland_client_perftests\": \"961d1114879e03f592752945959df04ea8f94921\", \"wm_unittests\": \"27c13c7790f12fc7bd90288808a0cc950ed331c4\", \"wtf_unittests\": \"f3caee06b1c0439553de3064cc57430e88cd2423\", \"zlib_unittests\": \"1c8875b97b5e95bb20b9d57e1088f359250db0c7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_057d00cc9cf4bf141513fc3b802f5b4dacb7566e9fed03d4c1bbb1efa2ee2ffd_v2\", \"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_37a6efb75484d21403d66fa62f2f0e5111772231983ba4ec0093d39fca548be8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a942695c23e0ee058ce93eb378e4719ec038e7b245f732574f7a4ff5c9d830ce_v2\", \"builder_cccbb10c846899f06deffa42caa19db86cd5babce89846fb5ee94576babbc4b9_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-120-8ubm\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611674017000448",
"status": "COMPLETED",
"status_changed_ts": "1611677197935611",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44918",
"builder:linux-chromeos-rel",
"buildset:commit/git/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49c33167881729cccbc3f597d783ae2cc5ae2ffe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022461154251620496",
"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/8857031092222484112/+/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:5158127a20142b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611677197964211",
"url": "https://ci.chromium.org/b/8857031092222484112",
"utcnow_ts": "1611677199724024"
}
}
[16:06:39.742] Invocation finished in 54m11.117918069s with status SUCCEEDED