[21:44:00.877] New invocation is queued and will start shortly
[21:44:04.011] Starting the invocation (attempt 1)
[21:44:04.095] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:44:04.095] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:44:04.095] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9035754274925461120",
"parameters_json": "{\"builder_name\":\"V8 Blink Win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"b7704fb1844875f658217c2c00b3accd0911d552\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@b7704fb1844875f658217c2c00b3accd0911d552\",\"title\":\"b7704fb1844875f658217c2c00b3accd0911d552\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"b7704fb1844875f658217c2c00b3accd0911d552\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Blink Win",
"scheduler_invocation_id:9035754274925461120",
"scheduler_job_id:v8/V8 Blink Win",
"user_agent:luci-scheduler",
"buildset:commit/git/b7704fb1844875f658217c2c00b3accd0911d552",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552",
"gitiles_ref:refs/heads/master"
]
}
[21:44:05.069] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:v8",
"created_ts": "1598996644262700",
"id": "8870324210705100064",
"parameters_json": "{\"builder_name\": \"V8 Blink Win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"b7704fb1844875f658217c2c00b3accd0911d552\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@b7704fb1844875f658217c2c00b3accd0911d552\", \"title\": \"b7704fb1844875f658217c2c00b3accd0911d552\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"b7704fb1844875f658217c2c00b3accd0911d552\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598996644768938",
"tags": [
"build_address:luci.v8.ci/V8 Blink Win/3377",
"builder:V8 Blink Win",
"buildset:commit/git/b7704fb1844875f658217c2c00b3accd0911d552",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035754274925461120",
"scheduler_job_id:v8/V8 Blink Win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8870324210705100064/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:chromium_integration",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1598996644769063",
"url": "https://ci.chromium.org/b/8870324210705100064",
"utcnow_ts": "1598996645001106"
}
}
[21:44:05.069] Task URL: https://ci.chromium.org/b/8870324210705100064
[21:44:05.073] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:2:0) after 1m0s
[21:45:05.357] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:2:0)
[21:45:05.357] Timer tick, asking Buildbucket for the build status
[21:45:05.518] Build 8870324210705100064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:05.524] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:3:0) after 1m0s
[21:46:05.548] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:3:0)
[21:46:05.548] Timer tick, asking Buildbucket for the build status
[21:46:05.654] Build 8870324210705100064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:05.654] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:4:0) after 1m0s
[21:47:05.714] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:4:0)
[21:47:05.714] Timer tick, asking Buildbucket for the build status
[21:47:06.205] Build 8870324210705100064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:06.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:5:0) after 1m0s
[21:47:42.593] Received PubSub notification, asking Buildbucket for the build status
[21:47:42.816] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:06.259] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:5:0)
[21:48:06.259] Timer tick, asking Buildbucket for the build status
[21:48:06.362] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:06.362] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:7:0) after 1m0s
[21:49:06.442] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:7:0)
[21:49:06.442] Timer tick, asking Buildbucket for the build status
[21:49:06.632] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:06.632] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:8:0) after 1m0s
[21:50:06.920] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:8:0)
[21:50:06.920] Timer tick, asking Buildbucket for the build status
[21:50:07.161] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:07.161] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:9:0) after 1m0s
[21:51:07.192] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:9:0)
[21:51:07.192] Timer tick, asking Buildbucket for the build status
[21:51:07.266] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:07.266] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:10:0) after 1m0s
[21:52:07.457] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:10:0)
[21:52:07.457] Timer tick, asking Buildbucket for the build status
[21:52:08.005] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:08.005] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:11:0) after 1m0s
[21:53:08.038] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:11:0)
[21:53:08.038] Timer tick, asking Buildbucket for the build status
[21:53:08.165] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.165] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:12:0) after 1m0s
[21:54:08.328] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:12:0)
[21:54:08.328] Timer tick, asking Buildbucket for the build status
[21:54:08.701] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:08.701] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:13:0) after 1m0s
[21:55:08.742] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:13:0)
[21:55:08.742] Timer tick, asking Buildbucket for the build status
[21:55:09.387] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:09.387] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:14:0) after 1m0s
[21:56:09.472] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:14:0)
[21:56:09.472] Timer tick, asking Buildbucket for the build status
[21:56:09.942] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:09.942] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:15:0) after 1m0s
[21:57:10.042] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:15:0)
[21:57:10.042] Timer tick, asking Buildbucket for the build status
[21:57:10.284] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:10.284] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:16:0) after 1m0s
[21:58:10.954] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:16:0)
[21:58:10.954] Timer tick, asking Buildbucket for the build status
[21:58:11.622] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.622] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:17:0) after 1m0s
[21:59:11.714] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:17:0)
[21:59:11.714] Timer tick, asking Buildbucket for the build status
[21:59:12.233] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:12.233] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:18:0) after 1m0s
[22:00:12.407] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:18:0)
[22:00:12.407] Timer tick, asking Buildbucket for the build status
[22:00:12.571] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.571] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:19:0) after 1m0s
[22:01:12.619] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:19:0)
[22:01:12.619] Timer tick, asking Buildbucket for the build status
[22:01:12.691] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:12.691] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:20:0) after 1m0s
[22:02:12.719] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:20:0)
[22:02:12.719] Timer tick, asking Buildbucket for the build status
[22:02:12.828] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:12.828] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:21:0) after 1m0s
[22:03:12.887] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:21:0)
[22:03:12.887] Timer tick, asking Buildbucket for the build status
[22:03:13.015] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:13.015] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:22:0) after 1m0s
[22:04:13.100] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:22:0)
[22:04:13.100] Timer tick, asking Buildbucket for the build status
[22:04:13.440] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:13.440] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:23:0) after 1m0s
[22:05:13.496] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:23:0)
[22:05:13.496] Timer tick, asking Buildbucket for the build status
[22:05:13.751] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:13.751] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:24:0) after 1m0s
[22:06:13.760] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:24:0)
[22:06:13.760] Timer tick, asking Buildbucket for the build status
[22:06:13.862] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:13.862] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:25:0) after 1m0s
[22:07:13.908] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:25:0)
[22:07:13.908] Timer tick, asking Buildbucket for the build status
[22:07:14.002] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:14.002] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:26:0) after 1m0s
[22:08:14.023] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:26:0)
[22:08:14.023] Timer tick, asking Buildbucket for the build status
[22:08:14.815] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:14.815] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:27:0) after 1m0s
[22:09:14.916] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:27:0)
[22:09:14.916] Timer tick, asking Buildbucket for the build status
[22:09:15.064] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:15.064] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:28:0) after 1m0s
[22:10:15.153] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:28:0)
[22:10:15.153] Timer tick, asking Buildbucket for the build status
[22:10:15.246] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:15.246] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:29:0) after 1m0s
[22:11:15.401] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:29:0)
[22:11:15.401] Timer tick, asking Buildbucket for the build status
[22:11:15.560] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:15.560] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:30:0) after 1m0s
[22:12:15.579] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:30:0)
[22:12:15.579] Timer tick, asking Buildbucket for the build status
[22:12:15.693] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:15.693] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:31:0) after 1m0s
[22:13:15.739] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:31:0)
[22:13:15.739] Timer tick, asking Buildbucket for the build status
[22:13:15.844] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:15.844] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:32:0) after 1m0s
[22:14:15.872] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:32:0)
[22:14:15.872] Timer tick, asking Buildbucket for the build status
[22:14:16.043] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:16.043] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:33:0) after 1m0s
[22:15:16.064] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:33:0)
[22:15:16.064] Timer tick, asking Buildbucket for the build status
[22:15:16.165] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:16.165] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:34:0) after 1m0s
[22:16:16.238] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:34:0)
[22:16:16.238] Timer tick, asking Buildbucket for the build status
[22:16:16.337] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:16.337] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:35:0) after 1m0s
[22:17:16.390] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:35:0)
[22:17:16.390] Timer tick, asking Buildbucket for the build status
[22:17:16.454] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:16.454] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:36:0) after 1m0s
[22:18:16.957] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:36:0)
[22:18:16.957] Timer tick, asking Buildbucket for the build status
[22:18:17.135] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:17.135] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:37:0) after 1m0s
[22:19:17.158] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:37:0)
[22:19:17.158] Timer tick, asking Buildbucket for the build status
[22:19:17.288] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:17.288] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:38:0) after 1m0s
[22:20:17.359] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:38:0)
[22:20:17.359] Timer tick, asking Buildbucket for the build status
[22:20:17.853] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:17.853] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:39:0) after 1m0s
[22:21:18.421] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:39:0)
[22:21:18.421] Timer tick, asking Buildbucket for the build status
[22:21:18.542] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:18.542] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:40:0) after 1m0s
[22:22:18.622] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:40:0)
[22:22:18.622] Timer tick, asking Buildbucket for the build status
[22:22:18.727] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:18.727] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:41:0) after 1m0s
[22:23:18.949] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:41:0)
[22:23:18.949] Timer tick, asking Buildbucket for the build status
[22:23:19.080] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:19.081] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:42:0) after 1m0s
[22:24:19.386] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:42:0)
[22:24:19.386] Timer tick, asking Buildbucket for the build status
[22:24:19.736] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:19.736] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:43:0) after 1m0s
[22:25:19.868] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:43:0)
[22:25:19.868] Timer tick, asking Buildbucket for the build status
[22:25:19.955] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:19.955] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:44:0) after 1m0s
[22:26:20.157] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:44:0)
[22:26:20.157] Timer tick, asking Buildbucket for the build status
[22:26:20.279] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:20.279] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:45:0) after 1m0s
[22:27:20.376] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:45:0)
[22:27:20.376] Timer tick, asking Buildbucket for the build status
[22:27:20.687] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:20.687] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:46:0) after 1m0s
[22:28:20.729] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:46:0)
[22:28:20.729] Timer tick, asking Buildbucket for the build status
[22:28:21.044] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:21.044] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:47:0) after 1m0s
[22:29:21.119] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:47:0)
[22:29:21.119] Timer tick, asking Buildbucket for the build status
[22:29:21.281] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:21.282] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:48:0) after 1m0s
[22:30:21.469] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:48:0)
[22:30:21.469] Timer tick, asking Buildbucket for the build status
[22:30:21.589] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:21.589] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:49:0) after 1m0s
[22:31:21.699] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:49:0)
[22:31:21.699] Timer tick, asking Buildbucket for the build status
[22:31:21.902] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:21.902] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:50:0) after 1m0s
[22:32:22.084] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:50:0)
[22:32:22.084] Timer tick, asking Buildbucket for the build status
[22:32:22.204] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:22.204] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:51:0) after 1m0s
[22:33:22.245] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:51:0)
[22:33:22.245] Timer tick, asking Buildbucket for the build status
[22:33:22.325] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:22.325] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:52:0) after 1m0s
[22:34:22.388] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:52:0)
[22:34:22.388] Timer tick, asking Buildbucket for the build status
[22:34:22.535] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:22.535] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:53:0) after 1m0s
[22:35:22.654] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:53:0)
[22:35:22.654] Timer tick, asking Buildbucket for the build status
[22:35:22.749] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:22.749] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:54:0) after 1m0s
[22:36:22.800] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:54:0)
[22:36:22.800] Timer tick, asking Buildbucket for the build status
[22:36:22.881] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:22.881] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:55:0) after 1m0s
[22:37:22.902] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:55:0)
[22:37:22.902] Timer tick, asking Buildbucket for the build status
[22:37:23.600] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:23.600] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:56:0) after 1m0s
[22:38:23.742] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:56:0)
[22:38:23.743] Timer tick, asking Buildbucket for the build status
[22:38:24.004] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:24.006] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:57:0) after 1m0s
[22:39:24.005] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:57:0)
[22:39:24.005] Timer tick, asking Buildbucket for the build status
[22:39:24.194] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:24.194] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:58:0) after 1m0s
[22:40:24.282] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:58:0)
[22:40:24.282] Timer tick, asking Buildbucket for the build status
[22:40:24.492] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:24.492] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:59:0) after 1m0s
[22:41:24.534] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:59:0)
[22:41:24.534] Timer tick, asking Buildbucket for the build status
[22:41:24.629] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:24.629] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:60:0) after 1m0s
[22:42:24.667] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:60:0)
[22:42:24.667] Timer tick, asking Buildbucket for the build status
[22:42:24.758] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:24.758] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:61:0) after 1m0s
[22:43:24.849] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:61:0)
[22:43:24.849] Timer tick, asking Buildbucket for the build status
[22:43:25.120] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:25.120] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:62:0) after 1m0s
[22:44:25.158] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:62:0)
[22:44:25.158] Timer tick, asking Buildbucket for the build status
[22:44:25.567] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:25.567] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:63:0) after 1m0s
[22:45:25.600] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:63:0)
[22:45:25.600] Timer tick, asking Buildbucket for the build status
[22:45:25.695] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:25.695] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:64:0) after 1m0s
[22:46:25.756] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:64:0)
[22:46:25.756] Timer tick, asking Buildbucket for the build status
[22:46:25.922] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:25.922] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:65:0) after 1m0s
[22:47:26.094] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:65:0)
[22:47:26.094] Timer tick, asking Buildbucket for the build status
[22:47:26.206] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.207] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:66:0) after 1m0s
[22:48:26.248] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:66:0)
[22:48:26.248] Timer tick, asking Buildbucket for the build status
[22:48:26.412] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:26.412] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:67:0) after 1m0s
[22:49:26.423] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:67:0)
[22:49:26.423] Timer tick, asking Buildbucket for the build status
[22:49:26.598] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:26.598] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:68:0) after 1m0s
[22:50:26.619] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:68:0)
[22:50:26.619] Timer tick, asking Buildbucket for the build status
[22:50:26.713] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:26.713] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:69:0) after 1m0s
[22:51:26.738] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:69:0)
[22:51:26.738] Timer tick, asking Buildbucket for the build status
[22:51:26.852] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:26.852] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:70:0) after 1m0s
[22:52:26.877] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:70:0)
[22:52:26.877] Timer tick, asking Buildbucket for the build status
[22:52:26.997] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:26.997] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:71:0) after 1m0s
[22:53:27.153] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:71:0)
[22:53:27.153] Timer tick, asking Buildbucket for the build status
[22:53:27.383] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:27.383] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:72:0) after 1m0s
[22:54:27.454] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:72:0)
[22:54:27.454] Timer tick, asking Buildbucket for the build status
[22:54:27.779] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:27.779] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:73:0) after 1m0s
[22:55:27.823] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:73:0)
[22:55:27.823] Timer tick, asking Buildbucket for the build status
[22:55:27.935] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:27.935] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:74:0) after 1m0s
[22:56:28.201] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:74:0)
[22:56:28.201] Timer tick, asking Buildbucket for the build status
[22:56:28.369] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:28.369] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:75:0) after 1m0s
[22:57:28.504] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:75:0)
[22:57:28.504] Timer tick, asking Buildbucket for the build status
[22:57:28.883] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:28.883] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:76:0) after 1m0s
[22:58:29.005] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:76:0)
[22:58:29.005] Timer tick, asking Buildbucket for the build status
[22:58:29.111] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:29.111] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:77:0) after 1m0s
[22:59:29.233] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:77:0)
[22:59:29.233] Timer tick, asking Buildbucket for the build status
[22:59:29.405] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:29.405] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:78:0) after 1m0s
[23:00:29.503] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:78:0)
[23:00:29.503] Timer tick, asking Buildbucket for the build status
[23:00:29.597] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:29.597] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:79:0) after 1m0s
[23:01:29.683] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:79:0)
[23:01:29.683] Timer tick, asking Buildbucket for the build status
[23:01:29.778] Build 8870324210705100064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:29.778] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9035754274925461120:80:0) after 1m0s
[23:01:44.270] Received PubSub notification, asking Buildbucket for the build status
[23:01:44.409] Build 8870324210705100064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:01:44.409] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1599001302214203",
"created_by": "project:v8",
"created_ts": "1598996644262700",
"id": "8870324210705100064",
"parameters_json": "{\"builder_name\": \"V8 Blink Win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"b7704fb1844875f658217c2c00b3accd0911d552\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@b7704fb1844875f658217c2c00b3accd0911d552\", \"title\": \"b7704fb1844875f658217c2c00b3accd0911d552\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"b7704fb1844875f658217c2c00b3accd0911d552\"}}",
"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\": \"b7704fb1844875f658217c2c00b3accd0911d552\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@b7704fb1844875f658217c2c00b3accd0911d552\", \"title\": \"b7704fb1844875f658217c2c00b3accd0911d552\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552\"}]}, \"bot_id\": \"luci-v8-ci-win10-0-4xgh\", \"branch\": \"refs/heads/master\", \"buildername\": \"V8 Blink Win\", \"buildnumber\": 3377, \"got_angle_revision\": \"736c43a1bb8fd492274a7c1c1e944b9ef9698804\", \"got_cr_revision\": \"54cd2ba35b4e640ee2f1a833c4fcbd20318b9f93\", \"got_cr_revision_cp\": \"refs/heads/master@{#803653}\", \"got_dawn_revision\": \"be53792880bc1d84d71d45c32cb2391acb76ca77\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"9822be5c1b44556b280fdc405ba0fb010b5b8e61\", \"got_revision_cp\": \"refs/heads/8.7.43@{#1}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"4953712980a299d5147255c4821ebf2278856e00\", \"got_v8_revision\": \"9822be5c1b44556b280fdc405ba0fb010b5b8e61\", \"got_v8_revision_cp\": \"refs/heads/8.7.43@{#1}\", \"got_webrtc_revision\": \"2597a1b22c0a906023d7cb204a91bb49c2b96c64\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32025}\", \"isolate_server\": \"https://isolateserver.appspot.com/\", \"mastername\": \"client.v8.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium_integration\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"b7704fb1844875f658217c2c00b3accd0911d552\", \"swarm_hashes\": {\"blink_web_tests\": \"44000e0d0a799c51df343a56e4c6b6f578e8d8a7\"}, \"swarm_hashes_refs/heads/8.7.43(at){#1}_without_patch\": {\"blink_web_tests\": \"44000e0d0a799c51df343a56e4c6b6f578e8d8a7\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_9904f97ef32843c5a9965a05151a82a583242aafe74830087d6efccf8144076a_v2\", \"builder_c45cd84c3011deeeb1bb95553d013b3b691464b45620fcd454d65c09381ae99d_v2\", \"builder_c7b0a343a5e32062186b7050cd89db3f02192c562f6b809eeb1b421a0a8dadb6_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-4xgh\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"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\": [\"5507-6e8d655\"], \"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": "1598996861974405",
"status": "COMPLETED",
"status_changed_ts": "1599001303133222",
"tags": [
"build_address:luci.v8.ci/V8 Blink Win/3377",
"builder:V8 Blink Win",
"buildset:commit/git/b7704fb1844875f658217c2c00b3accd0911d552",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/b7704fb1844875f658217c2c00b3accd0911d552",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035754274925461120",
"scheduler_job_id:v8/V8 Blink Win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8870324210705100064/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium_integration",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4e64720d46efa310",
"user_agent:luci-scheduler"
],
"updated_ts": "1599001303193525",
"url": "https://ci.chromium.org/b/8870324210705100064",
"utcnow_ts": "1599001304398942"
}
}
[23:01:44.409] Invocation finished in 1h17m43.555409841s with status SUCCEEDED