[13:00:03.024] New invocation is queued and will start shortly
[13:00:04.517] Starting the invocation (attempt 1)
[13:00:04.596] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:00:04.596] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:00:04.596] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019751576849722720",
"parameters_json": "{\"builder_name\":\"ios-webkit-tot\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ios-webkit-tot",
"scheduler_invocation_id:9019751576849722720",
"scheduler_job_id:chromium/ios-webkit-tot",
"user_agent:luci-scheduler"
]
}
[13:00:05.861] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614258004743528",
"id": "8854321514376383504",
"parameters_json": "{\"builder_name\": \"ios-webkit-tot\", \"properties\": {}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614258005558734",
"tags": [
"build_address:luci.chromium.ci/ios-webkit-tot/2100",
"builder:ios-webkit-tot",
"scheduler_invocation_id:9019751576849722720",
"scheduler_job_id:chromium/ios-webkit-tot",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8854321514376383504/+/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": "1614258005558923",
"url": "https://ci.chromium.org/b/8854321514376383504",
"utcnow_ts": "1614258005840960"
}
}
[13:00:05.861] Task URL: https://ci.chromium.org/b/8854321514376383504
[13:00:05.861] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:2:0) after 1m0s
[13:00:56.854] Received PubSub notification, asking Buildbucket for the build status
[13:00:57.093] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:06.907] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:2:0)
[13:01:06.907] Timer tick, asking Buildbucket for the build status
[13:01:08.375] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:08.375] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:4:0) after 1m0s
[13:02:08.527] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:4:0)
[13:02:08.527] Timer tick, asking Buildbucket for the build status
[13:02:08.589] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:08.589] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:5:0) after 1m0s
[13:03:08.572] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:5:0)
[13:03:08.572] Timer tick, asking Buildbucket for the build status
[13:03:08.814] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:08.814] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:6:0) after 1m0s
[13:04:08.914] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:6:0)
[13:04:08.914] Timer tick, asking Buildbucket for the build status
[13:04:09.208] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:09.208] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:7:0) after 1m0s
[13:05:09.266] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:7:0)
[13:05:09.266] Timer tick, asking Buildbucket for the build status
[13:05:09.345] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:09.345] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:8:0) after 1m0s
[13:06:09.368] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:8:0)
[13:06:09.368] Timer tick, asking Buildbucket for the build status
[13:06:09.449] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:09.449] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:9:0) after 1m0s
[13:07:09.503] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:9:0)
[13:07:09.503] Timer tick, asking Buildbucket for the build status
[13:07:09.574] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:09.574] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:10:0) after 1m0s
[13:08:09.639] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:10:0)
[13:08:09.639] Timer tick, asking Buildbucket for the build status
[13:08:09.718] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:09.718] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:11:0) after 1m0s
[13:09:09.777] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:11:0)
[13:09:09.777] Timer tick, asking Buildbucket for the build status
[13:09:09.876] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:09.876] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:12:0) after 1m0s
[13:10:09.980] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:12:0)
[13:10:09.980] Timer tick, asking Buildbucket for the build status
[13:10:10.053] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:10.053] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:13:0) after 1m0s
[13:11:10.087] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:13:0)
[13:11:10.087] Timer tick, asking Buildbucket for the build status
[13:11:10.161] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:10.161] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:14:0) after 1m0s
[13:12:10.245] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:14:0)
[13:12:10.245] Timer tick, asking Buildbucket for the build status
[13:12:10.545] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:10.545] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:15:0) after 1m0s
[13:13:10.546] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:15:0)
[13:13:10.546] Timer tick, asking Buildbucket for the build status
[13:13:10.615] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:10.615] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:16:0) after 1m0s
[13:14:10.667] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:16:0)
[13:14:10.667] Timer tick, asking Buildbucket for the build status
[13:14:10.844] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:10.844] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:17:0) after 1m0s
[13:15:10.883] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:17:0)
[13:15:10.883] Timer tick, asking Buildbucket for the build status
[13:15:10.958] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:10.958] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:18:0) after 1m0s
[13:16:10.984] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:18:0)
[13:16:10.984] Timer tick, asking Buildbucket for the build status
[13:16:11.071] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:11.071] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:19:0) after 1m0s
[13:17:11.088] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:19:0)
[13:17:11.088] Timer tick, asking Buildbucket for the build status
[13:17:11.188] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:11.188] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:20:0) after 1m0s
[13:18:11.202] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:20:0)
[13:18:11.202] Timer tick, asking Buildbucket for the build status
[13:18:11.573] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:11.573] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:21:0) after 1m0s
[13:19:11.614] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:21:0)
[13:19:11.614] Timer tick, asking Buildbucket for the build status
[13:19:11.728] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:11.728] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:22:0) after 1m0s
[13:20:12.022] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:22:0)
[13:20:12.022] Timer tick, asking Buildbucket for the build status
[13:20:12.105] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:12.105] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:23:0) after 1m0s
[13:21:12.302] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:23:0)
[13:21:12.302] Timer tick, asking Buildbucket for the build status
[13:21:12.512] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:12.512] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:24:0) after 1m0s
[13:22:12.552] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:24:0)
[13:22:12.552] Timer tick, asking Buildbucket for the build status
[13:22:12.701] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:12.701] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:25:0) after 1m0s
[13:23:12.777] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:25:0)
[13:23:12.777] Timer tick, asking Buildbucket for the build status
[13:23:12.865] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:12.865] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:26:0) after 1m0s
[13:24:13.016] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:26:0)
[13:24:13.016] Timer tick, asking Buildbucket for the build status
[13:24:13.093] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:13.093] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:27:0) after 1m0s
[13:25:13.223] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:27:0)
[13:25:13.223] Timer tick, asking Buildbucket for the build status
[13:25:13.298] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:13.298] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:28:0) after 1m0s
[13:26:13.445] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:28:0)
[13:26:13.445] Timer tick, asking Buildbucket for the build status
[13:26:13.524] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:13.524] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:29:0) after 1m0s
[13:27:13.665] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:29:0)
[13:27:13.665] Timer tick, asking Buildbucket for the build status
[13:27:13.734] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:13.734] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:30:0) after 1m0s
[13:28:13.819] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:30:0)
[13:28:13.819] Timer tick, asking Buildbucket for the build status
[13:28:13.896] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:13.896] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:31:0) after 1m0s
[13:29:13.986] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:31:0)
[13:29:13.986] Timer tick, asking Buildbucket for the build status
[13:29:14.156] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:14.156] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:32:0) after 1m0s
[13:30:14.157] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:32:0)
[13:30:14.158] Timer tick, asking Buildbucket for the build status
[13:30:14.325] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:14.325] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:33:0) after 1m0s
[13:31:14.382] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:33:0)
[13:31:14.382] Timer tick, asking Buildbucket for the build status
[13:31:14.743] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:14.743] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:34:0) after 1m0s
[13:32:14.750] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:34:0)
[13:32:14.750] Timer tick, asking Buildbucket for the build status
[13:32:14.828] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:14.828] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:35:0) after 1m0s
[13:33:14.845] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:35:0)
[13:33:14.846] Timer tick, asking Buildbucket for the build status
[13:33:15.017] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:15.017] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:36:0) after 1m0s
[13:34:15.077] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:36:0)
[13:34:15.077] Timer tick, asking Buildbucket for the build status
[13:34:15.252] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:15.252] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:37:0) after 1m0s
[13:35:15.278] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:37:0)
[13:35:15.278] Timer tick, asking Buildbucket for the build status
[13:35:15.342] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:15.342] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:38:0) after 1m0s
[13:36:15.401] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:38:0)
[13:36:15.401] Timer tick, asking Buildbucket for the build status
[13:36:15.511] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:15.511] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:39:0) after 1m0s
[13:37:15.610] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:39:0)
[13:37:15.610] Timer tick, asking Buildbucket for the build status
[13:37:15.678] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:15.678] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:40:0) after 1m0s
[13:38:15.731] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:40:0)
[13:38:15.731] Timer tick, asking Buildbucket for the build status
[13:38:15.788] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:15.788] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:41:0) after 1m0s
[13:39:15.829] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:41:0)
[13:39:15.829] Timer tick, asking Buildbucket for the build status
[13:39:16.034] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:16.034] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:42:0) after 1m0s
[13:40:16.052] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:42:0)
[13:40:16.052] Timer tick, asking Buildbucket for the build status
[13:40:16.277] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:16.277] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:43:0) after 1m0s
[13:41:16.293] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:43:0)
[13:41:16.293] Timer tick, asking Buildbucket for the build status
[13:41:16.511] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:16.511] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:44:0) after 1m0s
[13:42:16.528] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:44:0)
[13:42:16.528] Timer tick, asking Buildbucket for the build status
[13:42:16.755] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:16.755] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:45:0) after 1m0s
[13:43:16.772] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:45:0)
[13:43:16.772] Timer tick, asking Buildbucket for the build status
[13:43:16.854] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:16.854] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:46:0) after 1m0s
[13:44:17.124] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:46:0)
[13:44:17.124] Timer tick, asking Buildbucket for the build status
[13:44:17.223] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:17.223] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:47:0) after 1m0s
[13:45:17.371] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:47:0)
[13:45:17.371] Timer tick, asking Buildbucket for the build status
[13:45:17.473] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:17.473] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:48:0) after 1m0s
[13:46:17.498] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:48:0)
[13:46:17.498] Timer tick, asking Buildbucket for the build status
[13:46:17.699] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:17.699] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:49:0) after 1m0s
[13:47:17.764] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:49:0)
[13:47:17.764] Timer tick, asking Buildbucket for the build status
[13:47:17.890] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:17.890] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:50:0) after 1m0s
[13:48:17.940] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:50:0)
[13:48:17.940] Timer tick, asking Buildbucket for the build status
[13:48:18.084] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:18.084] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:51:0) after 1m0s
[13:49:18.176] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:51:0)
[13:49:18.176] Timer tick, asking Buildbucket for the build status
[13:49:18.240] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:18.240] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:52:0) after 1m0s
[13:50:18.339] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:52:0)
[13:50:18.339] Timer tick, asking Buildbucket for the build status
[13:50:18.406] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:18.406] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:53:0) after 1m0s
[13:51:18.413] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:53:0)
[13:51:18.413] Timer tick, asking Buildbucket for the build status
[13:51:18.590] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:18.590] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:54:0) after 1m0s
[13:52:18.625] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:54:0)
[13:52:18.625] Timer tick, asking Buildbucket for the build status
[13:52:18.810] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:18.810] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:55:0) after 1m0s
[13:53:18.817] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:55:0)
[13:53:18.817] Timer tick, asking Buildbucket for the build status
[13:53:18.889] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:18.889] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:56:0) after 1m0s
[13:54:18.918] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:56:0)
[13:54:18.918] Timer tick, asking Buildbucket for the build status
[13:54:18.998] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:18.998] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:57:0) after 1m0s
[13:55:19.056] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:57:0)
[13:55:19.056] Timer tick, asking Buildbucket for the build status
[13:55:19.132] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:19.132] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:58:0) after 1m0s
[13:56:19.228] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:58:0)
[13:56:19.228] Timer tick, asking Buildbucket for the build status
[13:56:19.331] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:19.331] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:59:0) after 1m0s
[13:57:19.547] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:59:0)
[13:57:19.547] Timer tick, asking Buildbucket for the build status
[13:57:19.830] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:19.830] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:60:0) after 1m0s
[13:58:19.820] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:60:0)
[13:58:19.821] Timer tick, asking Buildbucket for the build status
[13:58:20.031] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:20.031] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:61:0) after 1m0s
[13:59:20.104] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:61:0)
[13:59:20.104] Timer tick, asking Buildbucket for the build status
[13:59:20.446] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:20.446] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:62:0) after 1m0s
[14:00:20.535] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:62:0)
[14:00:20.535] Timer tick, asking Buildbucket for the build status
[14:00:20.821] Build 8854321514376383504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:20.821] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9019751576849722720:63:0) after 1m0s
[14:01:15.566] Received PubSub notification, asking Buildbucket for the build status
[14:01:15.943] Build 8854321514376383504: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[14:01:15.943] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614261675220001",
"created_by": "project:chromium",
"created_ts": "1614258004743528",
"failure_reason": "INFRA_FAILURE",
"id": "8854321514376383504",
"parameters_json": "{\"builder_name\": \"ios-webkit-tot\", \"properties\": {}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('bot_update') (timeout)\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build712-m9\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"ios-webkit-tot\", \"buildnumber\": 2100, \"path_config\": \"generic\", \"recipe\": \"chromium\", \"xcode_build_version\": \"11e608cwk\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"ios-webkit-tot\"], \"caches\": [\"builder_ee5270859593433257ec2e924501a0172ef9cf6984ec369ea1b628f31fb17995_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11e608cwk\"], \"cores\": [\"12\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-8700B\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:3e9b\"], \"hidpi\": [\"0\"], \"id\": [\"build712-m9\"], \"mac_model\": [\"Macmini8,1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Mac\", \"Mac-10\", \"Mac-10.15\", \"Mac-10.15.4\", \"Mac-10.15.4-19E266\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('bot_update') (timeout)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614258055922792",
"status": "COMPLETED",
"status_changed_ts": "1614258056450161",
"tags": [
"build_address:luci.chromium.ci/ios-webkit-tot/2100",
"builder:ios-webkit-tot",
"scheduler_invocation_id:9019751576849722720",
"scheduler_job_id:chromium/ios-webkit-tot",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8854321514376383504/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51f217f503a35710",
"user_agent:luci-scheduler"
],
"updated_ts": "1614261675220401",
"url": "https://ci.chromium.org/b/8854321514376383504",
"utcnow_ts": "1614261675911379"
}
}
[14:01:15.943] Invocation finished in 1h1m12.942826292s with status FAILED