[15:20:46.203] New invocation is queued and will start shortly
[15:20:47.298] Starting the invocation (attempt 1)
[15:20:47.381] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[15:20:47.384] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[15:20:47.384] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9020014514430200096",
"parameters_json": "{\"builder_name\":\"V8 Win64 - dev image\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@856a39f4fe57a3c10c2caffef2497d3cc4d05b13\",\"title\":\"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"bd7da6516a9543dc4673b536616027f5f698ce65\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@bd7da6516a9543dc4673b536616027f5f698ce65\",\"title\":\"bd7da6516a9543dc4673b536616027f5f698ce65\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/bd7da6516a9543dc4673b536616027f5f698ce65\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@075c4ffe61631b3dd6c2da970f3274be4a1cdb10\",\"title\":\"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"25a3e85589864ec57164e1af0e9195b4ccfaef17\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@25a3e85589864ec57164e1af0e9195b4ccfaef17\",\"title\":\"25a3e85589864ec57164e1af0e9195b4ccfaef17\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/25a3e85589864ec57164e1af0e9195b4ccfaef17\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"569cddb24ad7485a9b3480b50bbb5786771ef353\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@569cddb24ad7485a9b3480b50bbb5786771ef353\",\"title\":\"569cddb24ad7485a9b3480b50bbb5786771ef353\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/569cddb24ad7485a9b3480b50bbb5786771ef353\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"d3b30c990f55fd9389a811ddb04d6c4666af5319\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d3b30c990f55fd9389a811ddb04d6c4666af5319\",\"title\":\"d3b30c990f55fd9389a811ddb04d6c4666af5319\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Win64 - dev image",
"scheduler_invocation_id:9020014514430200096",
"scheduler_job_id:v8/V8 Win64 - dev image",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"gitiles_ref:refs/heads/master"
]
}
[15:20:48.097] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:v8",
"created_ts": "1614007247478652",
"id": "8854584452426343904",
"parameters_json": "{\"builder_name\": \"V8 Win64 - dev image\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"title\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"bd7da6516a9543dc4673b536616027f5f698ce65\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@bd7da6516a9543dc4673b536616027f5f698ce65\", \"title\": \"bd7da6516a9543dc4673b536616027f5f698ce65\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/bd7da6516a9543dc4673b536616027f5f698ce65\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"title\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"title\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@569cddb24ad7485a9b3480b50bbb5786771ef353\", \"title\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/569cddb24ad7485a9b3480b50bbb5786771ef353\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"title\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614007247945761",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/555",
"builder:V8 Win64 - dev image",
"buildset:commit/git/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020014514430200096",
"scheduler_job_id:v8/V8 Win64 - dev image",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/v8/buildbucket/cr-buildbucket-dev.appspot.com/8854584452426343904/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614007247946046",
"url": "https://luci-milo-dev.appspot.com/b/8854584452426343904",
"utcnow_ts": "1614007248088164"
}
}
[15:20:48.097] Task URL: https://luci-milo-dev.appspot.com/b/8854584452426343904
[15:20:48.097] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:2:0) after 1m0s
[15:21:48.126] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:2:0)
[15:21:48.126] Timer tick, asking Buildbucket for the build status
[15:21:48.232] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:21:48.232] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:3:0) after 1m0s
[15:22:48.323] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:3:0)
[15:22:48.323] Timer tick, asking Buildbucket for the build status
[15:22:48.464] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:22:48.464] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:4:0) after 1m0s
[15:23:48.568] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:4:0)
[15:23:48.568] Timer tick, asking Buildbucket for the build status
[15:23:48.684] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:23:48.684] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:5:0) after 1m0s
[15:24:48.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:5:0)
[15:24:48.756] Timer tick, asking Buildbucket for the build status
[15:24:48.861] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:24:48.861] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:6:0) after 1m0s
[15:25:48.965] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:6:0)
[15:25:48.965] Timer tick, asking Buildbucket for the build status
[15:25:49.066] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:25:49.066] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:7:0) after 1m0s
[15:26:49.157] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:7:0)
[15:26:49.157] Timer tick, asking Buildbucket for the build status
[15:26:49.271] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:26:49.271] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:8:0) after 1m0s
[15:27:49.330] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:8:0)
[15:27:49.330] Timer tick, asking Buildbucket for the build status
[15:27:49.435] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:27:49.435] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:9:0) after 1m0s
[15:28:49.469] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:9:0)
[15:28:49.469] Timer tick, asking Buildbucket for the build status
[15:28:49.587] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:49.587] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:10:0) after 1m0s
[15:29:49.603] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:10:0)
[15:29:49.603] Timer tick, asking Buildbucket for the build status
[15:29:49.822] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:29:49.822] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:11:0) after 1m0s
[15:30:49.845] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:11:0)
[15:30:49.845] Timer tick, asking Buildbucket for the build status
[15:30:49.954] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:30:49.954] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:12:0) after 1m0s
[15:31:50.025] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:12:0)
[15:31:50.025] Timer tick, asking Buildbucket for the build status
[15:31:50.163] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:50.163] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:13:0) after 1m0s
[15:32:50.189] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:13:0)
[15:32:50.189] Timer tick, asking Buildbucket for the build status
[15:32:50.343] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:50.343] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:14:0) after 1m0s
[15:33:50.375] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:14:0)
[15:33:50.375] Timer tick, asking Buildbucket for the build status
[15:33:50.491] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:33:50.491] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:15:0) after 1m0s
[15:34:50.574] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:15:0)
[15:34:50.574] Timer tick, asking Buildbucket for the build status
[15:34:50.982] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:34:50.982] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:16:0) after 1m0s
[15:35:50.999] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:16:0)
[15:35:50.999] Timer tick, asking Buildbucket for the build status
[15:35:51.217] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:51.217] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:17:0) after 1m0s
[15:36:51.256] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:17:0)
[15:36:51.256] Timer tick, asking Buildbucket for the build status
[15:36:52.185] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:52.185] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:18:0) after 1m0s
[15:37:52.223] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:18:0)
[15:37:52.223] Timer tick, asking Buildbucket for the build status
[15:37:52.753] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:52.753] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:19:0) after 1m0s
[15:38:52.832] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:19:0)
[15:38:52.832] Timer tick, asking Buildbucket for the build status
[15:38:53.333] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:53.333] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:20:0) after 1m0s
[15:39:53.362] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:20:0)
[15:39:53.362] Timer tick, asking Buildbucket for the build status
[15:39:53.969] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:53.969] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:21:0) after 1m0s
[15:40:54.023] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:21:0)
[15:40:54.023] Timer tick, asking Buildbucket for the build status
[15:40:54.314] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:54.314] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:22:0) after 1m0s
[15:41:54.434] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:22:0)
[15:41:54.434] Timer tick, asking Buildbucket for the build status
[15:41:54.886] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:54.886] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:23:0) after 1m0s
[15:42:54.912] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:23:0)
[15:42:54.912] Timer tick, asking Buildbucket for the build status
[15:42:55.164] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:55.164] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:24:0) after 1m0s
[15:43:55.183] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:24:0)
[15:43:55.183] Timer tick, asking Buildbucket for the build status
[15:43:55.541] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:55.541] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:25:0) after 1m0s
[15:44:55.565] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:25:0)
[15:44:55.565] Timer tick, asking Buildbucket for the build status
[15:44:55.895] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:55.895] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:26:0) after 1m0s
[15:45:55.956] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:26:0)
[15:45:55.956] Timer tick, asking Buildbucket for the build status
[15:45:56.191] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:56.191] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:27:0) after 1m0s
[15:46:56.235] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:27:0)
[15:46:56.235] Timer tick, asking Buildbucket for the build status
[15:46:56.427] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:56.427] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:28:0) after 1m0s
[15:47:56.527] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:28:0)
[15:47:56.527] Timer tick, asking Buildbucket for the build status
[15:47:56.897] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:56.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:29:0) after 1m0s
[15:48:56.939] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:29:0)
[15:48:56.939] Timer tick, asking Buildbucket for the build status
[15:48:58.092] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:58.092] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:30:0) after 1m0s
[15:49:58.113] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:30:0)
[15:49:58.113] Timer tick, asking Buildbucket for the build status
[15:49:58.563] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:58.563] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:31:0) after 1m0s
[15:50:58.590] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:31:0)
[15:50:58.590] Timer tick, asking Buildbucket for the build status
[15:50:58.870] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:58.870] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:32:0) after 1m0s
[15:51:58.968] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:32:0)
[15:51:58.968] Timer tick, asking Buildbucket for the build status
[15:51:59.471] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:51:59.471] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:33:0) after 1m0s
[15:52:59.566] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:33:0)
[15:52:59.566] Timer tick, asking Buildbucket for the build status
[15:52:59.854] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:52:59.854] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:34:0) after 1m0s
[15:53:59.966] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:34:0)
[15:53:59.966] Timer tick, asking Buildbucket for the build status
[15:54:00.476] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:54:00.476] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:35:0) after 1m0s
[15:55:00.567] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:35:0)
[15:55:00.567] Timer tick, asking Buildbucket for the build status
[15:55:01.023] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:01.023] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:36:0) after 1m0s
[15:56:01.055] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:36:0)
[15:56:01.055] Timer tick, asking Buildbucket for the build status
[15:56:01.309] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:01.309] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:37:0) after 1m0s
[15:57:01.374] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:37:0)
[15:57:01.374] Timer tick, asking Buildbucket for the build status
[15:57:01.724] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:01.724] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:38:0) after 1m0s
[15:58:01.811] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:38:0)
[15:58:01.811] Timer tick, asking Buildbucket for the build status
[15:58:02.153] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:58:02.153] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:39:0) after 1m0s
[15:59:02.272] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:39:0)
[15:59:02.272] Timer tick, asking Buildbucket for the build status
[15:59:02.582] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:02.582] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:40:0) after 1m0s
[16:00:02.628] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:40:0)
[16:00:02.628] Timer tick, asking Buildbucket for the build status
[16:00:03.460] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:03.460] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:41:0) after 1m0s
[16:01:03.535] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:41:0)
[16:01:03.535] Timer tick, asking Buildbucket for the build status
[16:01:03.764] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:03.764] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:42:0) after 1m0s
[16:02:03.827] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:42:0)
[16:02:03.827] Timer tick, asking Buildbucket for the build status
[16:02:04.281] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:04.282] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:43:0) after 1m0s
[16:03:04.357] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:43:0)
[16:03:04.357] Timer tick, asking Buildbucket for the build status
[16:03:04.684] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:03:04.684] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:44:0) after 1m0s
[16:04:04.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:44:0)
[16:04:04.756] Timer tick, asking Buildbucket for the build status
[16:04:05.147] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:04:05.147] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:45:0) after 1m0s
[16:05:05.176] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:45:0)
[16:05:05.176] Timer tick, asking Buildbucket for the build status
[16:05:05.457] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:05.457] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:46:0) after 1m0s
[16:06:05.568] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:46:0)
[16:06:05.568] Timer tick, asking Buildbucket for the build status
[16:06:05.846] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:05.846] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:47:0) after 1m0s
[16:07:05.890] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:47:0)
[16:07:05.890] Timer tick, asking Buildbucket for the build status
[16:07:06.428] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:06.428] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:48:0) after 1m0s
[16:08:06.458] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:48:0)
[16:08:06.458] Timer tick, asking Buildbucket for the build status
[16:08:06.718] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:06.718] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:49:0) after 1m0s
[16:09:06.759] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:49:0)
[16:09:06.759] Timer tick, asking Buildbucket for the build status
[16:09:12.780] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:12.780] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:50:0) after 1m0s
[16:10:12.878] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:50:0)
[16:10:12.878] Timer tick, asking Buildbucket for the build status
[16:10:12.989] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:12.989] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:51:0) after 1m0s
[16:11:13.015] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:51:0)
[16:11:13.015] Timer tick, asking Buildbucket for the build status
[16:11:13.218] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:13.218] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:52:0) after 1m0s
[16:12:13.358] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:52:0)
[16:12:13.358] Timer tick, asking Buildbucket for the build status
[16:12:13.622] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:13.622] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:53:0) after 1m0s
[16:13:13.735] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:53:0)
[16:13:13.735] Timer tick, asking Buildbucket for the build status
[16:13:13.825] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:13.825] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:54:0) after 1m0s
[16:14:13.918] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:54:0)
[16:14:13.918] Timer tick, asking Buildbucket for the build status
[16:14:14.028] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:14.028] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:55:0) after 1m0s
[16:15:14.054] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:55:0)
[16:15:14.054] Timer tick, asking Buildbucket for the build status
[16:15:14.145] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:14.146] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:56:0) after 1m0s
[16:16:14.169] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:56:0)
[16:16:14.169] Timer tick, asking Buildbucket for the build status
[16:16:14.289] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:14.289] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:57:0) after 1m0s
[16:17:14.307] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:57:0)
[16:17:14.307] Timer tick, asking Buildbucket for the build status
[16:17:14.399] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:14.399] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:58:0) after 1m0s
[16:18:14.426] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:58:0)
[16:18:14.426] Timer tick, asking Buildbucket for the build status
[16:18:14.507] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:14.507] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:59:0) after 1m0s
[16:19:14.527] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:59:0)
[16:19:14.527] Timer tick, asking Buildbucket for the build status
[16:19:14.610] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:14.610] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:60:0) after 1m0s
[16:20:14.713] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:60:0)
[16:20:14.713] Timer tick, asking Buildbucket for the build status
[16:20:14.902] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:14.903] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:61:0) after 1m0s
[16:21:15.033] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:61:0)
[16:21:15.033] Timer tick, asking Buildbucket for the build status
[16:21:15.201] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:15.201] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:62:0) after 1m0s
[16:22:15.222] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:62:0)
[16:22:15.222] Timer tick, asking Buildbucket for the build status
[16:22:15.320] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:15.320] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:63:0) after 1m0s
[16:23:15.365] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:63:0)
[16:23:15.365] Timer tick, asking Buildbucket for the build status
[16:23:15.456] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:15.456] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:64:0) after 1m0s
[16:24:15.477] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:64:0)
[16:24:15.477] Timer tick, asking Buildbucket for the build status
[16:24:15.548] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:15.548] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:65:0) after 1m0s
[16:25:15.629] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:65:0)
[16:25:15.629] Timer tick, asking Buildbucket for the build status
[16:25:15.720] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:15.720] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:66:0) after 1m0s
[16:26:15.741] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:66:0)
[16:26:15.741] Timer tick, asking Buildbucket for the build status
[16:26:15.827] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:15.827] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:67:0) after 1m0s
[16:27:15.976] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:67:0)
[16:27:15.976] Timer tick, asking Buildbucket for the build status
[16:27:16.087] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:16.087] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:68:0) after 1m0s
[16:28:16.113] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:68:0)
[16:28:16.113] Timer tick, asking Buildbucket for the build status
[16:28:16.220] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:16.220] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:69:0) after 1m0s
[16:29:16.254] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:69:0)
[16:29:16.254] Timer tick, asking Buildbucket for the build status
[16:29:16.351] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:16.351] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:70:0) after 1m0s
[16:30:16.379] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:70:0)
[16:30:16.379] Timer tick, asking Buildbucket for the build status
[16:30:16.459] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:16.459] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:71:0) after 1m0s
[16:31:16.495] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:71:0)
[16:31:16.495] Timer tick, asking Buildbucket for the build status
[16:31:16.597] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:16.597] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:72:0) after 1m0s
[16:32:16.630] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:72:0)
[16:32:16.630] Timer tick, asking Buildbucket for the build status
[16:32:16.733] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:16.733] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:73:0) after 1m0s
[16:33:16.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:73:0)
[16:33:16.756] Timer tick, asking Buildbucket for the build status
[16:33:16.840] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:16.840] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:74:0) after 1m0s
[16:34:16.981] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:74:0)
[16:34:16.981] Timer tick, asking Buildbucket for the build status
[16:34:17.080] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:17.081] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:75:0) after 1m0s
[16:35:17.121] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:75:0)
[16:35:17.121] Timer tick, asking Buildbucket for the build status
[16:35:17.211] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:17.211] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:76:0) after 1m0s
[16:36:17.236] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:76:0)
[16:36:17.236] Timer tick, asking Buildbucket for the build status
[16:36:17.331] Build 8854584452426343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:17.331] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:77:0) after 1m0s
[16:36:21.413] Received PubSub notification, asking Buildbucket for the build status
[16:36:21.529] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:17.444] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:77:0)
[16:37:17.444] Timer tick, asking Buildbucket for the build status
[16:37:17.529] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:17.530] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:79:0) after 1m0s
[16:38:17.568] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:79:0)
[16:38:17.568] Timer tick, asking Buildbucket for the build status
[16:38:17.673] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:17.673] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:80:0) after 1m0s
[16:39:17.705] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:80:0)
[16:39:17.705] Timer tick, asking Buildbucket for the build status
[16:39:17.805] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:17.805] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:81:0) after 1m0s
[16:40:17.901] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:81:0)
[16:40:17.901] Timer tick, asking Buildbucket for the build status
[16:40:18.001] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:18.001] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:82:0) after 1m0s
[16:41:18.065] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:82:0)
[16:41:18.065] Timer tick, asking Buildbucket for the build status
[16:41:18.300] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:18.300] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:83:0) after 1m0s
[16:42:18.404] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:83:0)
[16:42:18.404] Timer tick, asking Buildbucket for the build status
[16:42:18.502] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:18.502] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:84:0) after 1m0s
[16:43:18.608] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:84:0)
[16:43:18.608] Timer tick, asking Buildbucket for the build status
[16:43:18.727] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:18.727] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:85:0) after 1m0s
[16:44:18.772] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:85:0)
[16:44:18.772] Timer tick, asking Buildbucket for the build status
[16:44:18.874] Build 8854584452426343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:18.874] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9020014514430200096:86:0) after 1m0s
[16:44:53.620] Received PubSub notification, asking Buildbucket for the build status
[16:44:53.721] Build 8854584452426343904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:44:53.721] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614012291924251",
"created_by": "project:v8",
"created_ts": "1614007247478652",
"id": "8854584452426343904",
"parameters_json": "{\"builder_name\": \"V8 Win64 - dev image\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"title\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"bd7da6516a9543dc4673b536616027f5f698ce65\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@bd7da6516a9543dc4673b536616027f5f698ce65\", \"title\": \"bd7da6516a9543dc4673b536616027f5f698ce65\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/bd7da6516a9543dc4673b536616027f5f698ce65\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"title\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"title\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@569cddb24ad7485a9b3480b50bbb5786771ef353\", \"title\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/569cddb24ad7485a9b3480b50bbb5786771ef353\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"title\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver-dev.appspot.com/\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"title\": \"856a39f4fe57a3c10c2caffef2497d3cc4d05b13\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/856a39f4fe57a3c10c2caffef2497d3cc4d05b13\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"bd7da6516a9543dc4673b536616027f5f698ce65\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@bd7da6516a9543dc4673b536616027f5f698ce65\", \"title\": \"bd7da6516a9543dc4673b536616027f5f698ce65\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/bd7da6516a9543dc4673b536616027f5f698ce65\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"title\": \"075c4ffe61631b3dd6c2da970f3274be4a1cdb10\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/075c4ffe61631b3dd6c2da970f3274be4a1cdb10\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"title\": \"25a3e85589864ec57164e1af0e9195b4ccfaef17\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/25a3e85589864ec57164e1af0e9195b4ccfaef17\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@569cddb24ad7485a9b3480b50bbb5786771ef353\", \"title\": \"569cddb24ad7485a9b3480b50bbb5786771ef353\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/569cddb24ad7485a9b3480b50bbb5786771ef353\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"title\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319\"}]}, \"bot_id\": \"dev-luci-chromium-ci-win10-0-771y\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Win64 - dev image\", \"buildnumber\": 555, \"got_revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\", \"got_revision_cp\": \"refs/heads/master@{#72915}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d3b30c990f55fd9389a811ddb04d6c4666af5319\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-771y\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5709-d3c4474\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614011756045483",
"status": "COMPLETED",
"status_changed_ts": "1614012292348979",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/555",
"builder:V8 Win64 - dev image",
"buildset:commit/git/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d3b30c990f55fd9389a811ddb04d6c4666af5319",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020014514430200096",
"scheduler_job_id:v8/V8 Win64 - dev image",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/v8/buildbucket/cr-buildbucket-dev.appspot.com/8854584452426343904/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51e325ae8406fd10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614012292414295",
"url": "https://luci-milo-dev.appspot.com/b/8854584452426343904",
"utcnow_ts": "1614012293713142"
}
}
[16:44:53.721] Invocation finished in 1h24m7.537383061s with status SUCCEEDED