[04:22:10.522] New invocation is queued and will start shortly
[04:22:11.715] Starting the invocation (attempt 1)
[04:22:11.754] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:22:11.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:22:11.754] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020508934462120384",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4cece460fc7cfb6cc945f6eadca25b01ef04b534\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9020508934462120384",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"gitiles_ref:refs/heads/master"
]
}
[04:22:12.403] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613535731833452",
"id": "8855078872415529120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4cece460fc7cfb6cc945f6eadca25b01ef04b534\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613535732270883",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45483",
"builder:linux-chromeos-rel",
"buildset:commit/git/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020508934462120384",
"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/8855078872415529120/+/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": "1613535732270994",
"url": "https://ci.chromium.org/b/8855078872415529120",
"utcnow_ts": "1613535732395543"
}
}
[04:22:12.403] Task URL: https://ci.chromium.org/b/8855078872415529120
[04:22:12.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:2:0) after 1m0s
[04:23:12.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:2:0)
[04:23:12.456] Timer tick, asking Buildbucket for the build status
[04:23:12.837] Build 8855078872415529120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:12.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:3:0) after 1m0s
[04:23:57.147] Received PubSub notification, asking Buildbucket for the build status
[04:23:57.230] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:12.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:3:0)
[04:24:12.931] Timer tick, asking Buildbucket for the build status
[04:24:13.034] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:13.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:5:0) after 1m0s
[04:25:13.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:5:0)
[04:25:13.099] Timer tick, asking Buildbucket for the build status
[04:25:13.292] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:13.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:6:0) after 1m0s
[04:26:13.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:6:0)
[04:26:13.347] Timer tick, asking Buildbucket for the build status
[04:26:13.903] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:13.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:7:0) after 1m0s
[04:27:13.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:7:0)
[04:27:13.963] Timer tick, asking Buildbucket for the build status
[04:27:14.093] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:8:0) after 1m0s
[04:28:14.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:8:0)
[04:28:14.280] Timer tick, asking Buildbucket for the build status
[04:28:14.380] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:14.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:9:0) after 1m0s
[04:29:14.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:9:0)
[04:29:14.396] Timer tick, asking Buildbucket for the build status
[04:29:14.567] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:14.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:10:0) after 1m0s
[04:30:14.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:10:0)
[04:30:14.620] Timer tick, asking Buildbucket for the build status
[04:30:14.746] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:14.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:11:0) after 1m0s
[04:31:14.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:11:0)
[04:31:14.806] Timer tick, asking Buildbucket for the build status
[04:31:14.880] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:14.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:12:0) after 1m0s
[04:32:14.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:12:0)
[04:32:14.937] Timer tick, asking Buildbucket for the build status
[04:32:15.056] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:15.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:13:0) after 1m0s
[04:33:15.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:13:0)
[04:33:15.064] Timer tick, asking Buildbucket for the build status
[04:33:15.141] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:15.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:14:0) after 1m0s
[04:34:15.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:14:0)
[04:34:15.114] Timer tick, asking Buildbucket for the build status
[04:34:15.323] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:15.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:15:0) after 1m0s
[04:35:15.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:15:0)
[04:35:15.344] Timer tick, asking Buildbucket for the build status
[04:35:15.439] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:15.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:16:0) after 1m0s
[04:36:16.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:16:0)
[04:36:16.051] Timer tick, asking Buildbucket for the build status
[04:36:16.374] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:16.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:17:0) after 1m0s
[04:37:16.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:17:0)
[04:37:16.436] Timer tick, asking Buildbucket for the build status
[04:37:16.571] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:16.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:18:0) after 1m0s
[04:38:16.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:18:0)
[04:38:16.591] Timer tick, asking Buildbucket for the build status
[04:38:16.726] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:16.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:19:0) after 1m0s
[04:39:16.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:19:0)
[04:39:16.731] Timer tick, asking Buildbucket for the build status
[04:39:16.835] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:16.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:20:0) after 1m0s
[04:40:16.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:20:0)
[04:40:16.910] Timer tick, asking Buildbucket for the build status
[04:40:17.007] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:21:0) after 1m0s
[04:41:17.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:21:0)
[04:41:17.049] Timer tick, asking Buildbucket for the build status
[04:41:17.148] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:17.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:22:0) after 1m0s
[04:42:17.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:22:0)
[04:42:17.173] Timer tick, asking Buildbucket for the build status
[04:42:17.289] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:17.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:23:0) after 1m0s
[04:43:17.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:23:0)
[04:43:17.336] Timer tick, asking Buildbucket for the build status
[04:43:17.421] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:17.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:24:0) after 1m0s
[04:44:17.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:24:0)
[04:44:17.404] Timer tick, asking Buildbucket for the build status
[04:44:17.555] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:17.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:25:0) after 1m0s
[04:45:17.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:25:0)
[04:45:17.538] Timer tick, asking Buildbucket for the build status
[04:45:17.770] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:17.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:26:0) after 1m0s
[04:46:17.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:26:0)
[04:46:17.928] Timer tick, asking Buildbucket for the build status
[04:46:18.118] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:18.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:27:0) after 1m0s
[04:47:18.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:27:0)
[04:47:18.114] Timer tick, asking Buildbucket for the build status
[04:47:18.212] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:18.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:28:0) after 1m0s
[04:48:18.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:28:0)
[04:48:18.339] Timer tick, asking Buildbucket for the build status
[04:48:18.809] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:18.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:29:0) after 1m0s
[04:49:18.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:29:0)
[04:49:18.853] Timer tick, asking Buildbucket for the build status
[04:49:19.135] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:19.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:30:0) after 1m0s
[04:50:19.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:30:0)
[04:50:19.253] Timer tick, asking Buildbucket for the build status
[04:50:19.434] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:19.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:31:0) after 1m0s
[04:51:19.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:31:0)
[04:51:19.447] Timer tick, asking Buildbucket for the build status
[04:51:19.538] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:19.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:32:0) after 1m0s
[04:52:19.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:32:0)
[04:52:19.541] Timer tick, asking Buildbucket for the build status
[04:52:19.908] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:19.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:33:0) after 1m0s
[04:53:19.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:33:0)
[04:53:19.936] Timer tick, asking Buildbucket for the build status
[04:53:20.519] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:20.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:34:0) after 1m0s
[04:54:20.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:34:0)
[04:54:20.683] Timer tick, asking Buildbucket for the build status
[04:54:20.985] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:20.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:35:0) after 1m0s
[04:55:21.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:35:0)
[04:55:21.097] Timer tick, asking Buildbucket for the build status
[04:55:21.173] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:21.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:36:0) after 1m0s
[04:56:21.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:36:0)
[04:56:21.217] Timer tick, asking Buildbucket for the build status
[04:56:21.286] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:21.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:37:0) after 1m0s
[04:57:21.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:37:0)
[04:57:21.459] Timer tick, asking Buildbucket for the build status
[04:57:21.569] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:21.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:38:0) after 1m0s
[04:58:21.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:38:0)
[04:58:21.583] Timer tick, asking Buildbucket for the build status
[04:58:21.655] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:21.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:39:0) after 1m0s
[04:59:21.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:39:0)
[04:59:21.717] Timer tick, asking Buildbucket for the build status
[04:59:21.896] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:21.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:40:0) after 1m0s
[05:00:21.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:40:0)
[05:00:21.920] Timer tick, asking Buildbucket for the build status
[05:00:22.098] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:22.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:41:0) after 1m0s
[05:01:22.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:41:0)
[05:01:22.159] Timer tick, asking Buildbucket for the build status
[05:01:22.488] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:22.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:42:0) after 1m0s
[05:02:22.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:42:0)
[05:02:22.529] Timer tick, asking Buildbucket for the build status
[05:02:22.811] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:22.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:43:0) after 1m0s
[05:03:22.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:43:0)
[05:03:22.828] Timer tick, asking Buildbucket for the build status
[05:03:22.913] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:22.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:44:0) after 1m0s
[05:04:22.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:44:0)
[05:04:22.930] Timer tick, asking Buildbucket for the build status
[05:04:23.232] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:23.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:45:0) after 1m0s
[05:05:23.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:45:0)
[05:05:23.299] Timer tick, asking Buildbucket for the build status
[05:05:23.396] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:23.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:46:0) after 1m0s
[05:06:23.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:46:0)
[05:06:23.413] Timer tick, asking Buildbucket for the build status
[05:06:23.504] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:23.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:47:0) after 1m0s
[05:07:23.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:47:0)
[05:07:23.584] Timer tick, asking Buildbucket for the build status
[05:07:23.728] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:23.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:48:0) after 1m0s
[05:08:23.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:48:0)
[05:08:23.863] Timer tick, asking Buildbucket for the build status
[05:08:23.965] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:23.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:49:0) after 1m0s
[05:09:23.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:49:0)
[05:09:23.967] Timer tick, asking Buildbucket for the build status
[05:09:24.100] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:24.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:50:0) after 1m0s
[05:10:24.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:50:0)
[05:10:24.151] Timer tick, asking Buildbucket for the build status
[05:10:24.503] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:24.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:51:0) after 1m0s
[05:11:24.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:51:0)
[05:11:24.529] Timer tick, asking Buildbucket for the build status
[05:11:25.047] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:25.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:52:0) after 1m0s
[05:12:25.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:52:0)
[05:12:25.097] Timer tick, asking Buildbucket for the build status
[05:12:25.869] Build 8855078872415529120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:25.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020508934462120384:53:0) after 1m0s
[05:12:39.430] Received PubSub notification, asking Buildbucket for the build status
[05:12:39.720] Build 8855078872415529120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:12:39.720] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613538757510619",
"created_by": "project:chromium",
"created_ts": "1613535731833452",
"id": "8855078872415529120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4cece460fc7cfb6cc945f6eadca25b01ef04b534\"}}",
"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\": \"4cece460fc7cfb6cc945f6eadca25b01ef04b534\", \"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\": 45483, \"got_angle_revision\": \"d7037aa2ce399d74bf7d8bc260fd67183ac1b213\", \"got_dawn_revision\": \"536c7aea7df989fd773e397ca34ed08ef7af03b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"4cece460fc7cfb6cc945f6eadca25b01ef04b534\", \"got_revision_cp\": \"refs/heads/master@{#854629}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"972f21b5ed71a418e8ed86f3b39bb7c2b7f175ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.197@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"4cece460fc7cfb6cc945f6eadca25b01ef04b534\", \"swarm_hashes_refs/heads/master(at){#854629}_without_patch\": {\"absl_hardening_tests\": \"213ad41901505fd3a0dfcdf8f4c02ae4f6df0973\", \"accessibility_unittests\": \"47ae6c98f9fd51fbfc0d52cb610e7566ea775fbb\", \"angle_unittests\": \"3c356bd93297b6d7daacab9f62cbeb47d4d7808e\", \"app_list_unittests\": \"1b1f3f61025d89ca2731313d5a4af3dd2381e2a2\", \"app_shell_unittests\": \"b4b6f821901224f875ae918face513a10d330091\", \"ash_unittests\": \"19c7c929d0771c7ea55cb9cc1ae6da7b8320ae6b\", \"aura_unittests\": \"1d342ed70b3976f67b083d4939b808f5e76869a7\", \"base_unittests\": \"238fc35503a9f3be706d3e5fac3fc8922936f267\", \"base_util_unittests\": \"95026e0acbb340c31be175fdd24158a547ba182e\", \"blink_common_unittests\": \"21cdf166d15d790965e16d656843b871af7a3d80\", \"blink_fuzzer_unittests\": \"605e26874cac566914ace1fcc8e62eabcc5796ed\", \"blink_heap_unittests\": \"e23d329714a94623028d548aa269457a43778056\", \"blink_platform_unittests\": \"d1810783003766c28bf34fd4919e759ac0d321f0\", \"blink_unittests\": \"6f9cb9479eaa3766a790f211b20578eacbc8a310\", \"boringssl_crypto_tests\": \"e211b9e44880dccfefdc13a3e6d9d284e8b63632\", \"boringssl_ssl_tests\": \"e653524a5a083c02da26e7ec710b91c9e78905a5\", \"browser_tests\": \"8256f60c41b03c5ede6874bc558ea2866646d611\", \"capture_unittests\": \"ea5cc9dc60d522092a45c2ae8f3f60e308fa37ed\", \"cast_unittests\": \"db519d47e94381b05556a1e686d939455ab5ff03\", \"cc_unittests\": \"20f1cfaad7d4ece6f61d18debfc18e6571b26196\", \"chrome_app_unittests\": \"bbf6acd311584162ccdf05eda6cad12e78b317be\", \"chromedriver_unittests\": \"74f52f860b6cb2365d6d8fe7213329e29e457feb\", \"chromeos_components_unittests\": \"4643fb5867fab532135873bea7e5583b1ce9ac94\", \"chromeos_unittests\": \"35e5a596e802a315ccab120a3042ee2e20b13733\", \"components_browsertests\": \"f1830138b8bb1739ea634efe917e370033a78a84\", \"components_unittests\": \"04fd2aaf689d93ffadd5369952a4f8a53bc2a99b\", \"compositor_unittests\": \"7c75e9994217e30ef19d2705b91cc7cb9ab975db\", \"content_browsertests\": \"6ae19b556912aeda9d9b09d64b5314493f283ab4\", \"content_unittests\": \"73a3d5db7a5cee9ee47f5b49dc24f1446c1dc015\", \"crashpad_tests\": \"a1a5e4637ecfefce19f84dc10059e120134c6086\", \"crypto_unittests\": \"ae1cb87a0a72f98efd6c234559bf709e4f0985fe\", \"dbus_unittests\": \"02b0d951f7d22f321dab905a2c0baa6ce000459d\", \"device_unittests\": \"52ec2c5b1d3028f75b965a43c67ef4d110207119\", \"display_unittests\": \"9a3ccff4d45c1eb09585544d3e9a66f17b2622ab\", \"events_unittests\": \"27a32649c5be29f5d3dc17181d9c92c433d5260c\", \"exo_unittests\": \"faaa3613f88aa8d0b119b2d4327846fae94fb2db\", \"extensions_browsertests\": \"726f77e9f9a3e8a8c0c1d088202e73c754c8e72c\", \"extensions_unittests\": \"eb16eb942333f0646398260f425fc9157599ecf4\", \"filesystem_service_unittests\": \"4ad64162cc863f4ebd6a770c80eb7666221474a9\", \"gcm_unit_tests\": \"8738363b33e950e7f448ea460278efbe6407ef1a\", \"gfx_unittests\": \"eccc2f6c25490c5e9b38b0f9062612c805828631\", \"gin_unittests\": \"f212c97422b0d7533831d31e8806265cb1ccc15b\", \"gl_unittests_ozone\": \"cb4737a9b59edf66bb8166c2aac5aed020679278\", \"google_apis_unittests\": \"7dce1a5522e7132e092fd98cabc844935af0369a\", \"gpu_unittests\": \"fbc99a6ff7c8b2259b52140c5140a2d06394b252\", \"gwp_asan_unittests\": \"5312e8853a6dfb3d6c07c047a367b515bff2c14f\", \"interactive_ui_tests\": \"eef3dd76d9843cba0d795ac29772d38f0e2eea51\", \"ipc_tests\": \"d7335f50f15a736c5c4a5ac75491d6f0f75684a0\", \"jingle_unittests\": \"d11ee668c51842f52522c49de0f625ed68b53e8b\", \"keyboard_unittests\": \"781ad56872d0094bcd0e9e5a46963de8adb33424\", \"latency_unittests\": \"2f35399236c0928c405484526ddb997a12208ee7\", \"libjingle_xmpp_unittests\": \"48b987e43c2e843c6ccb72ae664f03ff536475ac\", \"liburlpattern_unittests\": \"4883a5e78d57aef5790e670f6b2a6d78991d6ecd\", \"media_blink_unittests\": \"86a4944c452925b4d2978f5abf0add6b63cea7d4\", \"media_unittests\": \"2305fb50534def8ab3169007b7771f66daabf9c8\", \"message_center_unittests\": \"69cf8bac456ade447bf01ff12e6a01bf0d6298e8\", \"midi_unittests\": \"1219e2b1ca8042fc7d97a905788c17bcaa1a89f9\", \"mojo_core_unittests\": \"78a7439660e761502b0a8a0003ce8832a0aa6c71\", \"mojo_unittests\": \"215884de79c2035dd5b2edd1ee0bcb1adf64e5a5\", \"nacl_helper_nonsfi_unittests\": \"e00736d671be7528fd5994d116f804d5c03e67b1\", \"nacl_loader_unittests\": \"19b3e7ce02a79d8ce9cb97b27774a380c6728968\", \"native_theme_unittests\": \"efaa98c6b3890619d2b7d4e96dc81a7436d19563\", \"net_unittests\": \"6ace38f9e81be66e4ac64464ffd13a56706654eb\", \"ozone_gl_unittests\": \"eb73080403be99880b1097b89d85eb06e955877e\", \"ozone_unittests\": \"f390e54d7d683b110b14e843bb584429ab5fe63a\", \"ozone_x11_unittests\": \"5440eeabc83f45de69d1d0cfd3a3f7c46d6b8538\", \"pdf_unittests\": \"401accb86165b668c308daeab917cfc5b13eccfc\", \"perfetto_unittests\": \"769b246de6cc7a711dfffd49ef5cb4be5655419e\", \"ppapi_unittests\": \"9313067c4a2df83f765daa712ca0d8080e28850d\", \"printing_unittests\": \"f3e07a6713821bf7f4f8f79d425a3efc4f6b97ef\", \"remoting_unittests\": \"82a4f1c7ba8bfcd8236f69a0afcef6ec57a7bacb\", \"sandbox_linux_unittests\": \"bc8e1393bf9169facd374edeb67d6ff6c4967214\", \"service_manager_unittests\": \"87a55bd80996f4867aa2d38beb5ca18a8dc4a814\", \"services_unittests\": \"a6cf03ceac46bb1b9284e6abaa175b94101ea6a5\", \"shell_dialogs_unittests\": \"0fbf9eaba7eba6256cef0e0ceb2262e7f5dcc47e\", \"shell_encryption_unittests\": \"5bf5602b933822337641a63cf2199ff3c37a61e0\", \"skia_unittests\": \"7a723ad22262951c89721249d82158e6de93b496\", \"snapshot_unittests\": \"ef4a906edfd80435c30572033d614095eec71d2d\", \"sql_unittests\": \"27303244bcda4b5f5055b249061dd6a9075a6bd1\", \"storage_unittests\": \"d9f29a22c7049f398787e5488bb0c589042e3992\", \"sync_integration_tests\": \"f4ff9af4f5c8fdee3b4691cef8504de035be48e4\", \"traffic_annotation_auditor_unittests\": \"54689438f29e876e2e7e0ec4913650f352fb89ee\", \"ui_base_unittests\": \"987decdb7b7825f8e022c67e1e306aed47f6b33a\", \"ui_chromeos_unittests\": \"dbbad27b141f98a960e2c4f74258f4604f628753\", \"ui_touch_selection_unittests\": \"a32bc00820770b37ce034e9659ac4c7c6ef6e14a\", \"unit_tests\": \"a736f15fc8d53b3b305f49c4c3724b2d82436ba8\", \"url_unittests\": \"7354f70c096b1e228b67cf122894188fe6ceae0e\", \"usage_time_limit_unittests\": \"db13e301522b3246bfb418b8ddb4e8d350854a17\", \"views_unittests\": \"57490b3125ff05b3557274868642090e85d648cb\", \"viz_unittests\": \"c6787e6c049c7c3b618e9e1fbcb6de53c1ef3c29\", \"wayland_client_perftests\": \"3207ec5797f9e6aafb0b01359f929da5b04dd105\", \"wm_unittests\": \"a2a72ce357794e5b69d9a3b19812a25559d2ce6d\", \"wtf_unittests\": \"05d7d96ff9ea574897eb0db67f3f04677621aa33\", \"zlib_unittests\": \"7d6302286bb2ca3a0ce6d5282ea6036b8b8352ad\"}}, \"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": "1613535835786626",
"status": "COMPLETED",
"status_changed_ts": "1613538758376562",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45483",
"builder:linux-chromeos-rel",
"buildset:commit/git/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4cece460fc7cfb6cc945f6eadca25b01ef04b534",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020508934462120384",
"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/8855078872415529120/+/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:51c70aeb8cb64810",
"user_agent:luci-scheduler"
],
"updated_ts": "1613538758381632",
"url": "https://ci.chromium.org/b/8855078872415529120",
"utcnow_ts": "1613538759706837"
}
}
[05:12:39.720] Invocation finished in 50m29.222159389s with status SUCCEEDED