[17:38:33.707] New invocation is queued and will start shortly
[17:38:36.767] Starting the invocation (attempt 1)
[17:38:36.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:38:36.820] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:38:36.820] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9039574790070454384",
"parameters_json": "{\"builder_name\":\"V8 Win64 - msvc\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"627b8781a517fa59531ee07db54a16cba89fc3e1\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@627b8781a517fa59531ee07db54a16cba89fc3e1\",\"title\":\"627b8781a517fa59531ee07db54a16cba89fc3e1\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"627b8781a517fa59531ee07db54a16cba89fc3e1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Win64 - msvc",
"scheduler_invocation_id:9039574790070454384",
"scheduler_job_id:v8/V8 Win64 - msvc",
"user_agent:luci-scheduler",
"buildset:commit/git/627b8781a517fa59531ee07db54a16cba89fc3e1",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1",
"gitiles_ref:refs/heads/master"
]
}
[17:38:38.653] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1595353117024516",
"id": "8874144725921303536",
"parameters_json": "{\"builder_name\": \"V8 Win64 - msvc\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@627b8781a517fa59531ee07db54a16cba89fc3e1\", \"title\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595353118244479",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - msvc/14402",
"builder:V8 Win64 - msvc",
"buildset:commit/git/627b8781a517fa59531ee07db54a16cba89fc3e1",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039574790070454384",
"scheduler_job_id:v8/V8 Win64 - msvc",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8874144725921303536/+/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"
],
"updated_ts": "1595353118244642",
"url": "https://ci.chromium.org/b/8874144725921303536",
"utcnow_ts": "1595353118638027"
}
}
[17:38:38.653] Task URL: https://ci.chromium.org/b/8874144725921303536
[17:38:38.653] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:2:0) after 1m0s
[17:39:38.965] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:2:0)
[17:39:38.965] Timer tick, asking Buildbucket for the build status
[17:39:39.606] Build 8874144725921303536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:39.606] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:3:0) after 1m0s
[17:40:39.737] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:3:0)
[17:40:39.737] Timer tick, asking Buildbucket for the build status
[17:40:41.473] Build 8874144725921303536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:41.473] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:4:0) after 1m0s
[17:41:41.696] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:4:0)
[17:41:41.696] Timer tick, asking Buildbucket for the build status
[17:41:42.240] Build 8874144725921303536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:42.240] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:5:0) after 1m0s
[17:42:42.504] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:5:0)
[17:42:42.504] Timer tick, asking Buildbucket for the build status
[17:42:42.722] Build 8874144725921303536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:42.722] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:6:0) after 1m0s
[17:43:34.693] Received PubSub notification, asking Buildbucket for the build status
[17:43:35.521] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:42.828] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:6:0)
[17:43:42.828] Timer tick, asking Buildbucket for the build status
[17:43:42.928] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:42.929] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:8:0) after 1m0s
[17:44:44.133] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:8:0)
[17:44:44.133] Timer tick, asking Buildbucket for the build status
[17:44:44.398] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:44.398] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:9:0) after 1m0s
[17:45:44.666] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:9:0)
[17:45:44.666] Timer tick, asking Buildbucket for the build status
[17:45:45.078] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:45.078] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:10:0) after 1m0s
[17:46:45.133] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:10:0)
[17:46:45.133] Timer tick, asking Buildbucket for the build status
[17:46:47.063] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:47.063] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:11:0) after 1m0s
[17:47:47.367] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:11:0)
[17:47:47.367] Timer tick, asking Buildbucket for the build status
[17:47:47.517] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:47.517] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:12:0) after 1m0s
[17:48:47.596] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:12:0)
[17:48:47.596] Timer tick, asking Buildbucket for the build status
[17:48:47.976] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:47.976] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:13:0) after 1m0s
[17:49:48.040] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:13:0)
[17:49:48.040] Timer tick, asking Buildbucket for the build status
[17:49:49.367] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:49.367] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:14:0) after 1m0s
[17:50:49.495] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:14:0)
[17:50:49.495] Timer tick, asking Buildbucket for the build status
[17:50:49.897] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:49.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:15:0) after 1m0s
[17:51:49.921] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:15:0)
[17:51:49.921] Timer tick, asking Buildbucket for the build status
[17:51:50.201] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:50.201] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:16:0) after 1m0s
[17:52:50.190] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:16:0)
[17:52:50.190] Timer tick, asking Buildbucket for the build status
[17:52:50.527] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:50.527] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:17:0) after 1m0s
[17:53:50.554] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:17:0)
[17:53:50.554] Timer tick, asking Buildbucket for the build status
[17:53:50.790] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:50.790] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:18:0) after 1m0s
[17:54:50.817] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:18:0)
[17:54:50.817] Timer tick, asking Buildbucket for the build status
[17:54:52.338] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:52.338] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:19:0) after 1m0s
[17:55:52.364] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:19:0)
[17:55:52.364] Timer tick, asking Buildbucket for the build status
[17:55:53.183] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:53.183] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:20:0) after 1m0s
[17:56:53.246] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:20:0)
[17:56:53.246] Timer tick, asking Buildbucket for the build status
[17:56:53.505] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:53.505] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:21:0) after 1m0s
[17:57:53.537] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:21:0)
[17:57:53.537] Timer tick, asking Buildbucket for the build status
[17:57:54.125] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:54.125] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:22:0) after 1m0s
[17:58:54.200] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:22:0)
[17:58:54.200] Timer tick, asking Buildbucket for the build status
[17:58:54.435] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:54.435] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:23:0) after 1m0s
[17:59:54.456] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:23:0)
[17:59:54.456] Timer tick, asking Buildbucket for the build status
[17:59:54.776] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:54.776] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:24:0) after 1m0s
[18:00:54.800] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:24:0)
[18:00:54.800] Timer tick, asking Buildbucket for the build status
[18:00:56.261] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:56.261] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:25:0) after 1m0s
[18:01:56.319] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:25:0)
[18:01:56.319] Timer tick, asking Buildbucket for the build status
[18:01:56.468] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:56.468] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:26:0) after 1m0s
[18:02:56.541] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:26:0)
[18:02:56.541] Timer tick, asking Buildbucket for the build status
[18:02:56.749] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:56.749] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:27:0) after 1m0s
[18:03:56.757] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:27:0)
[18:03:56.757] Timer tick, asking Buildbucket for the build status
[18:03:57.029] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:57.030] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:28:0) after 1m0s
[18:04:57.089] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:28:0)
[18:04:57.089] Timer tick, asking Buildbucket for the build status
[18:04:57.301] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:57.301] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:29:0) after 1m0s
[18:05:57.324] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:29:0)
[18:05:57.324] Timer tick, asking Buildbucket for the build status
[18:05:57.451] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:57.451] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:30:0) after 1m0s
[18:06:57.496] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:30:0)
[18:06:57.496] Timer tick, asking Buildbucket for the build status
[18:06:58.106] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:58.106] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:31:0) after 1m0s
[18:07:58.195] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:31:0)
[18:07:58.195] Timer tick, asking Buildbucket for the build status
[18:07:58.758] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:58.758] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:32:0) after 1m0s
[18:08:58.939] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:32:0)
[18:08:58.939] Timer tick, asking Buildbucket for the build status
[18:08:59.214] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:59.214] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:33:0) after 1m0s
[18:09:59.280] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:33:0)
[18:09:59.280] Timer tick, asking Buildbucket for the build status
[18:09:59.391] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:59.391] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:34:0) after 1m0s
[18:10:59.424] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:34:0)
[18:10:59.424] Timer tick, asking Buildbucket for the build status
[18:10:59.740] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:59.740] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:35:0) after 1m0s
[18:11:59.881] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:35:0)
[18:11:59.881] Timer tick, asking Buildbucket for the build status
[18:12:00.254] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:00.254] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:36:0) after 1m0s
[18:13:00.266] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:36:0)
[18:13:00.266] Timer tick, asking Buildbucket for the build status
[18:13:00.468] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:00.468] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:37:0) after 1m0s
[18:14:01.078] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:37:0)
[18:14:01.078] Timer tick, asking Buildbucket for the build status
[18:14:01.897] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:01.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:38:0) after 1m0s
[18:15:03.014] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:38:0)
[18:15:03.014] Timer tick, asking Buildbucket for the build status
[18:15:03.657] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:03.657] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:39:0) after 1m0s
[18:16:03.737] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:39:0)
[18:16:03.737] Timer tick, asking Buildbucket for the build status
[18:16:04.101] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:04.101] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:40:0) after 1m0s
[18:17:04.145] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:40:0)
[18:17:04.145] Timer tick, asking Buildbucket for the build status
[18:17:04.305] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:04.305] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:41:0) after 1m0s
[18:18:04.354] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:41:0)
[18:18:04.354] Timer tick, asking Buildbucket for the build status
[18:18:04.637] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:04.637] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:42:0) after 1m0s
[18:19:04.699] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:42:0)
[18:19:04.699] Timer tick, asking Buildbucket for the build status
[18:19:04.915] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:04.915] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:43:0) after 1m0s
[18:20:05.014] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:43:0)
[18:20:05.014] Timer tick, asking Buildbucket for the build status
[18:20:05.320] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:05.320] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:44:0) after 1m0s
[18:21:05.386] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:44:0)
[18:21:05.386] Timer tick, asking Buildbucket for the build status
[18:21:05.844] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:05.844] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:45:0) after 1m0s
[18:22:05.859] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:45:0)
[18:22:05.860] Timer tick, asking Buildbucket for the build status
[18:22:06.568] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:06.568] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:46:0) after 1m0s
[18:23:06.663] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:46:0)
[18:23:06.663] Timer tick, asking Buildbucket for the build status
[18:23:07.005] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:07.005] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:47:0) after 1m0s
[18:24:07.184] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:47:0)
[18:24:07.185] Timer tick, asking Buildbucket for the build status
[18:24:07.559] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:07.559] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:48:0) after 1m0s
[18:25:07.598] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:48:0)
[18:25:07.598] Timer tick, asking Buildbucket for the build status
[18:25:07.964] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:07.964] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:49:0) after 1m0s
[18:26:08.015] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:49:0)
[18:26:08.015] Timer tick, asking Buildbucket for the build status
[18:26:08.385] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:08.386] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:50:0) after 1m0s
[18:27:08.511] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:50:0)
[18:27:08.511] Timer tick, asking Buildbucket for the build status
[18:27:08.642] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:08.642] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:51:0) after 1m0s
[18:28:08.656] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:51:0)
[18:28:08.656] Timer tick, asking Buildbucket for the build status
[18:28:08.965] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:08.965] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:52:0) after 1m0s
[18:29:09.063] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:52:0)
[18:29:09.063] Timer tick, asking Buildbucket for the build status
[18:29:09.694] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:09.694] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:53:0) after 1m0s
[18:30:09.845] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:53:0)
[18:30:09.845] Timer tick, asking Buildbucket for the build status
[18:30:13.521] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:13.521] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:54:0) after 1m0s
[18:31:13.520] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:54:0)
[18:31:13.520] Timer tick, asking Buildbucket for the build status
[18:31:14.361] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:14.361] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:55:0) after 1m0s
[18:32:14.476] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:55:0)
[18:32:14.476] Timer tick, asking Buildbucket for the build status
[18:32:14.685] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:14.685] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:56:0) after 1m0s
[18:33:14.740] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:56:0)
[18:33:14.740] Timer tick, asking Buildbucket for the build status
[18:33:14.896] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:14.896] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:57:0) after 1m0s
[18:34:14.962] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:57:0)
[18:34:14.962] Timer tick, asking Buildbucket for the build status
[18:34:15.639] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:15.639] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:58:0) after 1m0s
[18:35:15.666] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:58:0)
[18:35:15.666] Timer tick, asking Buildbucket for the build status
[18:35:15.891] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:15.891] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:59:0) after 1m0s
[18:36:15.944] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:59:0)
[18:36:15.944] Timer tick, asking Buildbucket for the build status
[18:36:16.241] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:16.242] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:60:0) after 1m0s
[18:37:16.289] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:60:0)
[18:37:16.289] Timer tick, asking Buildbucket for the build status
[18:37:16.402] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:16.402] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:61:0) after 1m0s
[18:38:16.521] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:61:0)
[18:38:16.521] Timer tick, asking Buildbucket for the build status
[18:38:17.196] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:17.196] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:62:0) after 1m0s
[18:39:17.234] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:62:0)
[18:39:17.234] Timer tick, asking Buildbucket for the build status
[18:39:17.317] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:17.317] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:63:0) after 1m0s
[18:40:17.393] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:63:0)
[18:40:17.393] Timer tick, asking Buildbucket for the build status
[18:40:17.614] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:17.614] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:64:0) after 1m0s
[18:41:17.644] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:64:0)
[18:41:17.644] Timer tick, asking Buildbucket for the build status
[18:41:17.799] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:17.799] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:65:0) after 1m0s
[18:42:17.865] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:65:0)
[18:42:17.865] Timer tick, asking Buildbucket for the build status
[18:42:17.981] Build 8874144725921303536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:17.981] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - msvc:9039574790070454384:66:0) after 1m0s
[18:42:56.607] Received PubSub notification, asking Buildbucket for the build status
[18:42:56.769] Build 8874144725921303536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:42:56.769] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1595356975240807",
"created_by": "project:v8",
"created_ts": "1595353117024516",
"id": "8874144725921303536",
"parameters_json": "{\"builder_name\": \"V8 Win64 - msvc\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@627b8781a517fa59531ee07db54a16cba89fc3e1\", \"title\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@627b8781a517fa59531ee07db54a16cba89fc3e1\", \"title\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1\"}]}, \"bot_id\": \"luci-v8-ci-win10-0-z5xd\", \"branch\": \"refs/heads/master\", \"build_config\": \"Release\", \"buildername\": \"V8 Win64 - msvc\", \"buildnumber\": 14402, \"got_revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\", \"got_revision_cp\": \"refs/heads/master@{#68972}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.v8\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"627b8781a517fa59531ee07db54a16cba89fc3e1\", \"use_goma\": false}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-v8-ci-win10-0-z5xd\"], \"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.v8.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5425-e4e3059\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1595353413799710",
"status": "COMPLETED",
"status_changed_ts": "1595356976216801",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - msvc/14402",
"builder:V8 Win64 - msvc",
"buildset:commit/git/627b8781a517fa59531ee07db54a16cba89fc3e1",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/627b8781a517fa59531ee07db54a16cba89fc3e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039574790070454384",
"scheduler_job_id:v8/V8 Win64 - msvc",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8874144725921303536/+/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:4d8b4640930aaf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1595356976227657",
"url": "https://ci.chromium.org/b/8874144725921303536",
"utcnow_ts": "1595356976745980"
}
}
[18:42:56.769] Invocation finished in 1h4m23.085971746s with status SUCCEEDED