[21:49:26.381] New invocation is queued and will start shortly
[21:49:27.482] Starting the invocation (attempt 1)
[21:49:27.531] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:49:27.538] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:49:27.538] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9039649603157743008",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"fa26e9658e699b03e7485a9c8dce5909037c0bbe\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa26e9658e699b03e7485a9c8dce5909037c0bbe\",\"title\":\"fa26e9658e699b03e7485a9c8dce5909037c0bbe\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/fa26e9658e699b03e7485a9c8dce5909037c0bbe\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0ef0afd868b99ee3a613277e05f4706c781c7d6a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0ef0afd868b99ee3a613277e05f4706c781c7d6a\",\"title\":\"0ef0afd868b99ee3a613277e05f4706c781c7d6a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0ef0afd868b99ee3a613277e05f4706c781c7d6a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1009aac0b7b4203bcc342474f8672a8592c155fd\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1009aac0b7b4203bcc342474f8672a8592c155fd\",\"title\":\"1009aac0b7b4203bcc342474f8672a8592c155fd\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/1009aac0b7b4203bcc342474f8672a8592c155fd\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\",\"title\":\"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9039649603157743008",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"gitiles_ref:refs/heads/master"
]
}
[21:49:28.529] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1595281767693420",
"id": "8874219541117623824",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fa26e9658e699b03e7485a9c8dce5909037c0bbe\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa26e9658e699b03e7485a9c8dce5909037c0bbe\", \"title\": \"fa26e9658e699b03e7485a9c8dce5909037c0bbe\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fa26e9658e699b03e7485a9c8dce5909037c0bbe\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0ef0afd868b99ee3a613277e05f4706c781c7d6a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0ef0afd868b99ee3a613277e05f4706c781c7d6a\", \"title\": \"0ef0afd868b99ee3a613277e05f4706c781c7d6a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0ef0afd868b99ee3a613277e05f4706c781c7d6a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1009aac0b7b4203bcc342474f8672a8592c155fd\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1009aac0b7b4203bcc342474f8672a8592c155fd\", \"title\": \"1009aac0b7b4203bcc342474f8672a8592c155fd\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1009aac0b7b4203bcc342474f8672a8592c155fd\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\", \"title\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595281768375768",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17316",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039649603157743008",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8874219541117623824/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1595281768375893",
"url": "https://luci-milo-dev.appspot.com/b/8874219541117623824",
"utcnow_ts": "1595281768517090"
}
}
[21:49:28.529] Task URL: https://luci-milo-dev.appspot.com/b/8874219541117623824
[21:49:28.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:2:0) after 1m0s
[21:50:28.628] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:2:0)
[21:50:28.628] Timer tick, asking Buildbucket for the build status
[21:50:28.749] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:28.749] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:3:0) after 1m0s
[21:51:28.870] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:3:0)
[21:51:28.870] Timer tick, asking Buildbucket for the build status
[21:51:29.026] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:29.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:4:0) after 1m0s
[21:52:29.053] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:4:0)
[21:52:29.053] Timer tick, asking Buildbucket for the build status
[21:52:29.171] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:29.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:5:0) after 1m0s
[21:53:29.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:5:0)
[21:53:29.270] Timer tick, asking Buildbucket for the build status
[21:53:29.459] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:29.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:6:0) after 1m0s
[21:54:29.483] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:6:0)
[21:54:29.483] Timer tick, asking Buildbucket for the build status
[21:54:29.603] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:29.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:7:0) after 1m0s
[21:55:29.677] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:7:0)
[21:55:29.677] Timer tick, asking Buildbucket for the build status
[21:55:29.805] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:29.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:8:0) after 1m0s
[21:56:29.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:8:0)
[21:56:29.943] Timer tick, asking Buildbucket for the build status
[21:56:30.065] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:30.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:9:0) after 1m0s
[21:57:30.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:9:0)
[21:57:30.094] Timer tick, asking Buildbucket for the build status
[21:57:30.243] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:30.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:10:0) after 1m0s
[21:58:30.284] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:10:0)
[21:58:30.284] Timer tick, asking Buildbucket for the build status
[21:58:30.463] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:30.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:11:0) after 1m0s
[21:59:30.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:11:0)
[21:59:30.489] Timer tick, asking Buildbucket for the build status
[21:59:30.685] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:30.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:12:0) after 1m0s
[22:00:30.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:12:0)
[22:00:30.773] Timer tick, asking Buildbucket for the build status
[22:00:30.917] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:30.917] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:13:0) after 1m0s
[22:01:30.976] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:13:0)
[22:01:30.976] Timer tick, asking Buildbucket for the build status
[22:01:31.170] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:31.170] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:14:0) after 1m0s
[22:02:31.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:14:0)
[22:02:31.281] Timer tick, asking Buildbucket for the build status
[22:02:31.447] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:31.448] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:15:0) after 1m0s
[22:03:31.473] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:15:0)
[22:03:31.473] Timer tick, asking Buildbucket for the build status
[22:03:31.608] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:31.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:16:0) after 1m0s
[22:04:31.669] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:16:0)
[22:04:31.669] Timer tick, asking Buildbucket for the build status
[22:04:31.886] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:31.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:17:0) after 1m0s
[22:05:31.937] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:17:0)
[22:05:31.937] Timer tick, asking Buildbucket for the build status
[22:05:32.112] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:32.112] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:18:0) after 1m0s
[22:06:32.172] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:18:0)
[22:06:32.172] Timer tick, asking Buildbucket for the build status
[22:06:32.321] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:32.321] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:19:0) after 1m0s
[22:07:32.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:19:0)
[22:07:32.343] Timer tick, asking Buildbucket for the build status
[22:07:32.486] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:32.486] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:20:0) after 1m0s
[22:08:32.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:20:0)
[22:08:32.509] Timer tick, asking Buildbucket for the build status
[22:08:32.642] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:32.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:21:0) after 1m0s
[22:09:32.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:21:0)
[22:09:32.675] Timer tick, asking Buildbucket for the build status
[22:09:32.796] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:32.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:22:0) after 1m0s
[22:10:32.820] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:22:0)
[22:10:32.820] Timer tick, asking Buildbucket for the build status
[22:10:32.974] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:32.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:23:0) after 1m0s
[22:11:33.073] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:23:0)
[22:11:33.073] Timer tick, asking Buildbucket for the build status
[22:11:33.601] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:33.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:24:0) after 1m0s
[22:12:33.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:24:0)
[22:12:33.678] Timer tick, asking Buildbucket for the build status
[22:12:33.826] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:33.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:25:0) after 1m0s
[22:13:33.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:25:0)
[22:13:33.866] Timer tick, asking Buildbucket for the build status
[22:13:34.008] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:34.008] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:26:0) after 1m0s
[22:14:34.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:26:0)
[22:14:34.074] Timer tick, asking Buildbucket for the build status
[22:14:34.393] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:34.393] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:27:0) after 1m0s
[22:15:34.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:27:0)
[22:15:34.458] Timer tick, asking Buildbucket for the build status
[22:15:34.589] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:34.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:28:0) after 1m0s
[22:16:34.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:28:0)
[22:16:34.690] Timer tick, asking Buildbucket for the build status
[22:16:34.866] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:34.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:29:0) after 1m0s
[22:17:34.926] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:29:0)
[22:17:34.926] Timer tick, asking Buildbucket for the build status
[22:17:35.057] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:35.057] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:30:0) after 1m0s
[22:18:35.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:30:0)
[22:18:35.079] Timer tick, asking Buildbucket for the build status
[22:18:35.192] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:35.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:31:0) after 1m0s
[22:19:35.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:31:0)
[22:19:35.302] Timer tick, asking Buildbucket for the build status
[22:19:35.456] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:35.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:32:0) after 1m0s
[22:20:35.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:32:0)
[22:20:35.557] Timer tick, asking Buildbucket for the build status
[22:20:35.756] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:35.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:33:0) after 1m0s
[22:21:35.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:33:0)
[22:21:35.807] Timer tick, asking Buildbucket for the build status
[22:21:35.960] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:35.960] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:34:0) after 1m0s
[22:22:36.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:34:0)
[22:22:36.193] Timer tick, asking Buildbucket for the build status
[22:22:36.371] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:36.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:35:0) after 1m0s
[22:23:36.409] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:35:0)
[22:23:36.409] Timer tick, asking Buildbucket for the build status
[22:23:36.550] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:36.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:36:0) after 1m0s
[22:24:36.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:36:0)
[22:24:36.574] Timer tick, asking Buildbucket for the build status
[22:24:36.712] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:36.712] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:37:0) after 1m0s
[22:25:36.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:37:0)
[22:25:36.805] Timer tick, asking Buildbucket for the build status
[22:25:36.944] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:36.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:38:0) after 1m0s
[22:26:36.968] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:38:0)
[22:26:36.968] Timer tick, asking Buildbucket for the build status
[22:26:37.105] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:37.105] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:39:0) after 1m0s
[22:27:37.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:39:0)
[22:27:37.166] Timer tick, asking Buildbucket for the build status
[22:27:37.315] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:37.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:40:0) after 1m0s
[22:28:37.341] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:40:0)
[22:28:37.341] Timer tick, asking Buildbucket for the build status
[22:28:37.492] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:37.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:41:0) after 1m0s
[22:29:37.535] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:41:0)
[22:29:37.535] Timer tick, asking Buildbucket for the build status
[22:29:37.653] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:37.653] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:42:0) after 1m0s
[22:30:37.683] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:42:0)
[22:30:37.683] Timer tick, asking Buildbucket for the build status
[22:30:37.844] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:37.844] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:43:0) after 1m0s
[22:31:37.886] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:43:0)
[22:31:37.886] Timer tick, asking Buildbucket for the build status
[22:31:38.033] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:38.033] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:44:0) after 1m0s
[22:32:38.126] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:44:0)
[22:32:38.126] Timer tick, asking Buildbucket for the build status
[22:32:38.273] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:38.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:45:0) after 1m0s
[22:33:38.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:45:0)
[22:33:38.303] Timer tick, asking Buildbucket for the build status
[22:33:38.431] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:38.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:46:0) after 1m0s
[22:34:38.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:46:0)
[22:34:38.487] Timer tick, asking Buildbucket for the build status
[22:34:38.619] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:38.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:47:0) after 1m0s
[22:35:38.665] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:47:0)
[22:35:38.665] Timer tick, asking Buildbucket for the build status
[22:35:38.807] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:38.808] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:48:0) after 1m0s
[22:36:38.836] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:48:0)
[22:36:38.836] Timer tick, asking Buildbucket for the build status
[22:36:38.989] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:38.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:49:0) after 1m0s
[22:37:39.026] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:49:0)
[22:37:39.026] Timer tick, asking Buildbucket for the build status
[22:37:39.164] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:39.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:50:0) after 1m0s
[22:38:39.243] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:50:0)
[22:38:39.243] Timer tick, asking Buildbucket for the build status
[22:38:39.400] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:39.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:51:0) after 1m0s
[22:39:39.437] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:51:0)
[22:39:39.437] Timer tick, asking Buildbucket for the build status
[22:39:39.831] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:39.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:52:0) after 1m0s
[22:40:39.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:52:0)
[22:40:39.904] Timer tick, asking Buildbucket for the build status
[22:40:40.093] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:40.093] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:53:0) after 1m0s
[22:41:40.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:53:0)
[22:41:40.127] Timer tick, asking Buildbucket for the build status
[22:41:40.356] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:40.356] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:54:0) after 1m0s
[22:42:40.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:54:0)
[22:42:40.391] Timer tick, asking Buildbucket for the build status
[22:42:40.518] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:40.518] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:55:0) after 1m0s
[22:43:40.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:55:0)
[22:43:40.568] Timer tick, asking Buildbucket for the build status
[22:43:40.839] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:40.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:56:0) after 1m0s
[22:44:40.878] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:56:0)
[22:44:40.878] Timer tick, asking Buildbucket for the build status
[22:44:41.003] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:41.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:57:0) after 1m0s
[22:45:41.055] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:57:0)
[22:45:41.055] Timer tick, asking Buildbucket for the build status
[22:45:41.195] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:41.195] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:58:0) after 1m0s
[22:46:41.262] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:58:0)
[22:46:41.262] Timer tick, asking Buildbucket for the build status
[22:46:41.388] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:41.388] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:59:0) after 1m0s
[22:47:41.426] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:59:0)
[22:47:41.426] Timer tick, asking Buildbucket for the build status
[22:47:41.772] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:41.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:60:0) after 1m0s
[22:48:41.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:60:0)
[22:48:41.799] Timer tick, asking Buildbucket for the build status
[22:48:42.142] Build 8874219541117623824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:42.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:61:0) after 1m0s
[22:49:12.646] Received PubSub notification, asking Buildbucket for the build status
[22:49:12.782] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:42.165] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:61:0)
[22:49:42.165] Timer tick, asking Buildbucket for the build status
[22:49:42.738] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:42.738] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:63:0) after 1m0s
[22:50:42.832] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:63:0)
[22:50:42.832] Timer tick, asking Buildbucket for the build status
[22:50:43.257] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:43.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:64:0) after 1m0s
[22:51:43.318] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:64:0)
[22:51:43.318] Timer tick, asking Buildbucket for the build status
[22:51:43.759] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:43.759] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:65:0) after 1m0s
[22:52:43.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:65:0)
[22:52:43.881] Timer tick, asking Buildbucket for the build status
[22:52:44.389] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:44.389] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:66:0) after 1m0s
[22:53:44.454] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:66:0)
[22:53:44.454] Timer tick, asking Buildbucket for the build status
[22:53:44.766] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:44.766] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:67:0) after 1m0s
[22:54:44.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:67:0)
[22:54:44.793] Timer tick, asking Buildbucket for the build status
[22:54:45.239] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:45.239] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:68:0) after 1m0s
[22:55:45.271] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:68:0)
[22:55:45.271] Timer tick, asking Buildbucket for the build status
[22:55:45.660] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:45.660] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:69:0) after 1m0s
[22:56:45.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:69:0)
[22:56:45.702] Timer tick, asking Buildbucket for the build status
[22:56:46.258] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:46.258] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:70:0) after 1m0s
[22:57:46.329] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:70:0)
[22:57:46.329] Timer tick, asking Buildbucket for the build status
[22:57:53.828] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:71:0) after 1m0s
[22:58:53.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:71:0)
[22:58:53.879] Timer tick, asking Buildbucket for the build status
[22:58:54.104] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:54.104] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:72:0) after 1m0s
[22:59:54.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:72:0)
[22:59:54.186] Timer tick, asking Buildbucket for the build status
[22:59:54.533] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:54.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:73:0) after 1m0s
[23:00:54.619] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:73:0)
[23:00:54.619] Timer tick, asking Buildbucket for the build status
[23:00:54.992] Build 8874219541117623824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:54.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9039649603157743008:74:0) after 1m0s
[23:01:04.626] Received PubSub notification, asking Buildbucket for the build status
[23:01:04.723] Build 8874219541117623824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:01:04.723] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1595286060755854",
"created_by": "project:infra",
"created_ts": "1595281767693420",
"id": "8874219541117623824",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"fa26e9658e699b03e7485a9c8dce5909037c0bbe\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fa26e9658e699b03e7485a9c8dce5909037c0bbe\", \"title\": \"fa26e9658e699b03e7485a9c8dce5909037c0bbe\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/fa26e9658e699b03e7485a9c8dce5909037c0bbe\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0ef0afd868b99ee3a613277e05f4706c781c7d6a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0ef0afd868b99ee3a613277e05f4706c781c7d6a\", \"title\": \"0ef0afd868b99ee3a613277e05f4706c781c7d6a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0ef0afd868b99ee3a613277e05f4706c781c7d6a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1009aac0b7b4203bcc342474f8672a8592c155fd\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1009aac0b7b4203bcc342474f8672a8592c155fd\", \"title\": \"1009aac0b7b4203bcc342474f8672a8592c155fd\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1009aac0b7b4203bcc342474f8672a8592c155fd\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\", \"title\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"0e24639d1455571cf6e2ae1d0aae9ba6e8af4665\", \"got_revision_cp\": \"refs/heads/master@{#33437}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-yl4r\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5423-ff485cc\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1595285350692193",
"status": "COMPLETED",
"status_changed_ts": "1595286062289329",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17316",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0e24639d1455571cf6e2ae1d0aae9ba6e8af4665",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039649603157743008",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8874219541117623824/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:4d87058a7cc30710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1595286062326874",
"url": "https://luci-milo-dev.appspot.com/b/8874219541117623824",
"utcnow_ts": "1595286064716250"
}
}
[23:01:04.723] Invocation finished in 1h11m38.35965355s with status SUCCEEDED