[07:00:02.688] New invocation is queued and will start shortly
[07:00:03.830] Starting the invocation (attempt 1)
[07:00:03.881] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:03.881] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:03.881] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020589599161163504",
"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:9020589599161163504",
"scheduler_job_id:chromium/ios-webkit-tot",
"user_agent:luci-scheduler"
]
}
[07:00:05.226] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613458804016152",
"id": "8855159537078872992",
"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": "1613458804634558",
"tags": [
"build_address:luci.chromium.ci/ios-webkit-tot/2063",
"builder:ios-webkit-tot",
"scheduler_invocation_id:9020589599161163504",
"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/8855159537078872992/+/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": "1613458804635147",
"url": "https://ci.chromium.org/b/8855159537078872992",
"utcnow_ts": "1613458805208865"
}
}
[07:00:05.226] Task URL: https://ci.chromium.org/b/8855159537078872992
[07:00:05.226] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:2:0) after 1m0s
[07:00:32.602] Received PubSub notification, asking Buildbucket for the build status
[07:00:32.829] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:05.666] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:2:0)
[07:01:05.666] Timer tick, asking Buildbucket for the build status
[07:01:06.040] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:06.040] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:4:0) after 1m0s
[07:02:06.157] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:4:0)
[07:02:06.157] Timer tick, asking Buildbucket for the build status
[07:02:06.257] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:06.257] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:5:0) after 1m0s
[07:03:06.306] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:5:0)
[07:03:06.306] Timer tick, asking Buildbucket for the build status
[07:03:06.523] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:06.523] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:6:0) after 1m0s
[07:04:06.550] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:6:0)
[07:04:06.550] Timer tick, asking Buildbucket for the build status
[07:04:06.638] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:06.638] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:7:0) after 1m0s
[07:05:06.648] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:7:0)
[07:05:06.649] Timer tick, asking Buildbucket for the build status
[07:05:07.129] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:07.129] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:8:0) after 1m0s
[07:06:07.138] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:8:0)
[07:06:07.138] Timer tick, asking Buildbucket for the build status
[07:06:07.240] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:07.240] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:9:0) after 1m0s
[07:07:07.255] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:9:0)
[07:07:07.255] Timer tick, asking Buildbucket for the build status
[07:07:07.607] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:07.607] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:10:0) after 1m0s
[07:08:07.689] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:10:0)
[07:08:07.689] Timer tick, asking Buildbucket for the build status
[07:08:07.766] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:07.766] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:11:0) after 1m0s
[07:09:07.920] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:11:0)
[07:09:07.920] Timer tick, asking Buildbucket for the build status
[07:09:08.110] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:08.110] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:12:0) after 1m0s
[07:10:08.618] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:12:0)
[07:10:08.618] Timer tick, asking Buildbucket for the build status
[07:10:08.688] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:13:0) after 1m0s
[07:11:09.728] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:13:0)
[07:11:09.728] Timer tick, asking Buildbucket for the build status
[07:11:09.984] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:09.984] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:14:0) after 1m0s
[07:12:10.083] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:14:0)
[07:12:10.083] Timer tick, asking Buildbucket for the build status
[07:12:10.334] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:15:0) after 1m0s
[07:13:10.358] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:15:0)
[07:13:10.358] Timer tick, asking Buildbucket for the build status
[07:13:10.479] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:10.479] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:16:0) after 1m0s
[07:14:10.519] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:16:0)
[07:14:10.519] Timer tick, asking Buildbucket for the build status
[07:14:10.589] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:10.589] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:17:0) after 1m0s
[07:15:10.613] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:17:0)
[07:15:10.613] Timer tick, asking Buildbucket for the build status
[07:15:10.700] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:10.701] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:18:0) after 1m0s
[07:16:10.715] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:18:0)
[07:16:10.715] Timer tick, asking Buildbucket for the build status
[07:16:10.825] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:10.825] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:19:0) after 1m0s
[07:17:10.847] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:19:0)
[07:17:10.847] Timer tick, asking Buildbucket for the build status
[07:17:10.917] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:10.917] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:20:0) after 1m0s
[07:18:10.973] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:20:0)
[07:18:10.973] Timer tick, asking Buildbucket for the build status
[07:18:11.156] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:11.156] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:21:0) after 1m0s
[07:19:11.214] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:21:0)
[07:19:11.214] Timer tick, asking Buildbucket for the build status
[07:19:11.350] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:11.350] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:22:0) after 1m0s
[07:20:11.367] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:22:0)
[07:20:11.367] Timer tick, asking Buildbucket for the build status
[07:20:11.441] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:11.441] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:23:0) after 1m0s
[07:21:11.438] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:23:0)
[07:21:11.438] Timer tick, asking Buildbucket for the build status
[07:21:11.702] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:11.702] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:24:0) after 1m0s
[07:22:11.847] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:24:0)
[07:22:11.847] Timer tick, asking Buildbucket for the build status
[07:22:12.032] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:12.032] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:25:0) after 1m0s
[07:23:12.173] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:25:0)
[07:23:12.173] Timer tick, asking Buildbucket for the build status
[07:23:12.283] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:12.283] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:26:0) after 1m0s
[07:24:12.281] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:26:0)
[07:24:12.281] Timer tick, asking Buildbucket for the build status
[07:24:12.368] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:12.368] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:27:0) after 1m0s
[07:25:12.385] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:27:0)
[07:25:12.385] Timer tick, asking Buildbucket for the build status
[07:25:12.538] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:12.538] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:28:0) after 1m0s
[07:26:12.595] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:28:0)
[07:26:12.595] Timer tick, asking Buildbucket for the build status
[07:26:12.929] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:12.929] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:29:0) after 1m0s
[07:27:13.028] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:29:0)
[07:27:13.028] Timer tick, asking Buildbucket for the build status
[07:27:13.142] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:13.142] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:30:0) after 1m0s
[07:28:13.295] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:30:0)
[07:28:13.295] Timer tick, asking Buildbucket for the build status
[07:28:13.365] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:13.365] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:31:0) after 1m0s
[07:29:13.475] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:31:0)
[07:29:13.475] Timer tick, asking Buildbucket for the build status
[07:29:13.548] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:13.548] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:32:0) after 1m0s
[07:30:13.729] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:32:0)
[07:30:13.729] Timer tick, asking Buildbucket for the build status
[07:30:13.836] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:13.836] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:33:0) after 1m0s
[07:31:15.335] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:33:0)
[07:31:15.335] Timer tick, asking Buildbucket for the build status
[07:31:15.743] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:15.743] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:34:0) after 1m0s
[07:32:16.297] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:34:0)
[07:32:16.297] Timer tick, asking Buildbucket for the build status
[07:32:16.380] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:16.380] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:35:0) after 1m0s
[07:33:16.609] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:35:0)
[07:33:16.609] Timer tick, asking Buildbucket for the build status
[07:33:16.695] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:16.695] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:36:0) after 1m0s
[07:34:16.820] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:36:0)
[07:34:16.820] Timer tick, asking Buildbucket for the build status
[07:34:16.961] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:16.961] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:37:0) after 1m0s
[07:35:17.147] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:37:0)
[07:35:17.147] Timer tick, asking Buildbucket for the build status
[07:35:17.448] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:17.448] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:38:0) after 1m0s
[07:36:17.548] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:38:0)
[07:36:17.548] Timer tick, asking Buildbucket for the build status
[07:36:17.636] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:17.636] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:39:0) after 1m0s
[07:37:17.746] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:39:0)
[07:37:17.746] Timer tick, asking Buildbucket for the build status
[07:37:17.838] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:17.838] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:40:0) after 1m0s
[07:38:17.982] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:40:0)
[07:38:17.982] Timer tick, asking Buildbucket for the build status
[07:38:18.320] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:18.320] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:41:0) after 1m0s
[07:39:18.458] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:41:0)
[07:39:18.458] Timer tick, asking Buildbucket for the build status
[07:39:18.914] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:18.914] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:42:0) after 1m0s
[07:40:18.934] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:42:0)
[07:40:18.934] Timer tick, asking Buildbucket for the build status
[07:40:19.199] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:19.199] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:43:0) after 1m0s
[07:41:19.262] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:43:0)
[07:41:19.262] Timer tick, asking Buildbucket for the build status
[07:41:19.335] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:19.335] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:44:0) after 1m0s
[07:42:19.423] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:44:0)
[07:42:19.423] Timer tick, asking Buildbucket for the build status
[07:42:19.510] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:19.510] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:45:0) after 1m0s
[07:43:19.674] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:45:0)
[07:43:19.674] Timer tick, asking Buildbucket for the build status
[07:43:19.783] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:19.783] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:46:0) after 1m0s
[07:44:19.849] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:46:0)
[07:44:19.849] Timer tick, asking Buildbucket for the build status
[07:44:19.926] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:19.926] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:47:0) after 1m0s
[07:45:19.956] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:47:0)
[07:45:19.956] Timer tick, asking Buildbucket for the build status
[07:45:20.223] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:20.223] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:48:0) after 1m0s
[07:46:20.274] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:48:0)
[07:46:20.274] Timer tick, asking Buildbucket for the build status
[07:46:20.352] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:20.352] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:49:0) after 1m0s
[07:47:20.439] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:49:0)
[07:47:20.439] Timer tick, asking Buildbucket for the build status
[07:47:20.534] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:20.534] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:50:0) after 1m0s
[07:48:20.543] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:50:0)
[07:48:20.543] Timer tick, asking Buildbucket for the build status
[07:48:20.613] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:20.613] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:51:0) after 1m0s
[07:49:20.635] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:51:0)
[07:49:20.635] Timer tick, asking Buildbucket for the build status
[07:49:20.928] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:20.929] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:52:0) after 1m0s
[07:50:21.012] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:52:0)
[07:50:21.012] Timer tick, asking Buildbucket for the build status
[07:50:21.152] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:21.152] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:53:0) after 1m0s
[07:51:21.183] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:53:0)
[07:51:21.183] Timer tick, asking Buildbucket for the build status
[07:51:21.248] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:21.248] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:54:0) after 1m0s
[07:52:21.325] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:54:0)
[07:52:21.325] Timer tick, asking Buildbucket for the build status
[07:52:21.471] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:21.471] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:55:0) after 1m0s
[07:53:21.556] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:55:0)
[07:53:21.556] Timer tick, asking Buildbucket for the build status
[07:53:21.716] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:21.716] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:56:0) after 1m0s
[07:54:21.705] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:56:0)
[07:54:21.705] Timer tick, asking Buildbucket for the build status
[07:54:21.804] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:21.804] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:57:0) after 1m0s
[07:55:21.826] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:57:0)
[07:55:21.826] Timer tick, asking Buildbucket for the build status
[07:55:21.948] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:21.948] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:58:0) after 1m0s
[07:56:22.002] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:58:0)
[07:56:22.002] Timer tick, asking Buildbucket for the build status
[07:56:22.071] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:22.071] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:59:0) after 1m0s
[07:57:22.097] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:59:0)
[07:57:22.097] Timer tick, asking Buildbucket for the build status
[07:57:22.170] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:22.170] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:60:0) after 1m0s
[07:58:22.201] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:60:0)
[07:58:22.201] Timer tick, asking Buildbucket for the build status
[07:58:22.267] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:22.267] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:61:0) after 1m0s
[07:59:22.298] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:61:0)
[07:59:22.298] Timer tick, asking Buildbucket for the build status
[07:59:22.539] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:22.539] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:62:0) after 1m0s
[08:00:22.626] Handling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:62:0)
[08:00:22.626] Timer tick, asking Buildbucket for the build status
[08:00:22.729] Build 8855159537078872992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:22.729] Scheduling timer "check-buildbucket-build-status" (chromium/ios-webkit-tot:9020589599161163504:63:0) after 1m0s
[08:00:50.901] Received PubSub notification, asking Buildbucket for the build status
[08:00:51.065] Build 8855159537078872992: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[08:00:51.065] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613462450245209",
"created_by": "project:chromium",
"created_ts": "1613458804016152",
"failure_reason": "INFRA_FAILURE",
"id": "8855159537078872992",
"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\": 2063, \"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\": [\"5700-b36f18b\"], \"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": "1613458831405319",
"status": "COMPLETED",
"status_changed_ts": "1613458831964612",
"tags": [
"build_address:luci.chromium.ci/ios-webkit-tot/2063",
"builder:ios-webkit-tot",
"scheduler_invocation_id:9020589599161163504",
"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/8855159537078872992/+/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:51c275245af90410",
"user_agent:luci-scheduler"
],
"updated_ts": "1613462450311639",
"url": "https://ci.chromium.org/b/8855159537078872992",
"utcnow_ts": "1613462451053146"
}
}
[08:00:51.065] Invocation finished in 1h0m48.419023757s with status FAILED