[14:44:22.985] New invocation is queued and will start shortly
[14:44:29.093] Starting the invocation (attempt 1)
[14:44:29.200] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:44:29.200] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:44:29.200] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9070479313985391408",
"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\":\"299c8059a27256565d924a2e6c0c1cbf5d460d51\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@299c8059a27256565d924a2e6c0c1cbf5d460d51\",\"title\":\"299c8059a27256565d924a2e6c0c1cbf5d460d51\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Blink Win",
"scheduler_invocation_id:9070479313985391408",
"scheduler_job_id:v8/V8 Blink Win",
"user_agent:luci-scheduler",
"buildset:commit/git/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"gitiles_ref:refs/heads/master"
]
}
[14:44:30.090] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1565880269417250",
"id": "8905049246574443728",
"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\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"title\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1565880269919246",
"tags": [
"build_address:luci.v8.ci/V8 Blink Win/67",
"builder:V8 Blink Win",
"buildset:commit/git/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070479313985391408",
"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/8905049246574443728/+/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": "1565880269919343",
"url": "https://ci.chromium.org/b/8905049246574443728",
"utcnow_ts": "1565880270070983"
}
}
[14:44:30.090] Task URL: https://ci.chromium.org/b/8905049246574443728
[14:44:30.090] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:2:0) after 1m0s
[14:45:18.204] Received PubSub notification, asking Buildbucket for the build status
[14:45:18.284] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:30.469] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:2:0)
[14:45:30.469] Timer tick, asking Buildbucket for the build status
[14:45:30.815] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:30.815] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:4:0) after 1m0s
[14:46:31.499] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:4:0)
[14:46:31.499] Timer tick, asking Buildbucket for the build status
[14:46:31.994] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:31.994] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:5:0) after 1m0s
[14:47:32.115] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:5:0)
[14:47:32.115] Timer tick, asking Buildbucket for the build status
[14:47:32.274] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:32.274] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:6:0) after 1m0s
[14:48:32.394] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:6:0)
[14:48:32.394] Timer tick, asking Buildbucket for the build status
[14:48:32.726] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:32.726] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:7:0) after 1m0s
[14:49:32.946] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:7:0)
[14:49:32.946] Timer tick, asking Buildbucket for the build status
[14:49:33.332] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:33.332] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:8:0) after 1m0s
[14:50:33.461] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:8:0)
[14:50:33.461] Timer tick, asking Buildbucket for the build status
[14:50:33.732] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:33.732] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:9:0) after 1m0s
[14:51:33.778] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:9:0)
[14:51:33.778] Timer tick, asking Buildbucket for the build status
[14:51:33.915] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:33.915] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:10:0) after 1m0s
[14:52:33.988] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:10:0)
[14:52:33.988] Timer tick, asking Buildbucket for the build status
[14:52:34.234] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:34.234] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:11:0) after 1m0s
[14:53:34.219] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:11:0)
[14:53:34.219] Timer tick, asking Buildbucket for the build status
[14:53:36.058] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:36.058] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:12:0) after 1m0s
[14:54:36.106] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:12:0)
[14:54:36.106] Timer tick, asking Buildbucket for the build status
[14:54:36.208] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:36.208] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:13:0) after 1m0s
[14:55:36.221] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:13:0)
[14:55:36.221] Timer tick, asking Buildbucket for the build status
[14:55:36.518] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:36.518] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:14:0) after 1m0s
[14:56:36.579] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:14:0)
[14:56:36.579] Timer tick, asking Buildbucket for the build status
[14:56:37.230] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:37.230] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:15:0) after 1m0s
[14:57:37.375] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:15:0)
[14:57:37.375] Timer tick, asking Buildbucket for the build status
[14:57:37.531] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:37.531] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:16:0) after 1m0s
[14:58:37.679] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:16:0)
[14:58:37.679] Timer tick, asking Buildbucket for the build status
[14:58:37.935] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:37.935] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:17:0) after 1m0s
[14:59:38.109] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:17:0)
[14:59:38.109] Timer tick, asking Buildbucket for the build status
[14:59:38.248] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:38.248] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:18:0) after 1m0s
[15:00:38.280] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:18:0)
[15:00:38.280] Timer tick, asking Buildbucket for the build status
[15:00:38.426] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:38.426] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:19:0) after 1m0s
[15:01:38.444] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:19:0)
[15:01:38.444] Timer tick, asking Buildbucket for the build status
[15:01:38.582] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:38.582] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:20:0) after 1m0s
[15:02:38.592] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:20:0)
[15:02:38.592] Timer tick, asking Buildbucket for the build status
[15:02:38.707] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:38.707] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:21:0) after 1m0s
[15:03:38.892] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:21:0)
[15:03:38.892] Timer tick, asking Buildbucket for the build status
[15:03:39.002] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:39.002] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:22:0) after 1m0s
[15:04:39.080] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:22:0)
[15:04:39.080] Timer tick, asking Buildbucket for the build status
[15:04:39.692] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:39.692] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:23:0) after 1m0s
[15:05:39.856] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:23:0)
[15:05:39.859] Timer tick, asking Buildbucket for the build status
[15:05:40.098] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:40.098] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:24:0) after 1m0s
[15:06:40.209] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:24:0)
[15:06:40.209] Timer tick, asking Buildbucket for the build status
[15:06:40.379] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:40.379] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:25:0) after 1m0s
[15:07:40.399] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:25:0)
[15:07:40.399] Timer tick, asking Buildbucket for the build status
[15:07:40.547] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:40.547] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:26:0) after 1m0s
[15:08:40.620] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:26:0)
[15:08:40.620] Timer tick, asking Buildbucket for the build status
[15:08:40.863] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:40.863] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:27:0) after 1m0s
[15:09:40.903] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:27:0)
[15:09:40.903] Timer tick, asking Buildbucket for the build status
[15:09:41.510] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:41.511] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:28:0) after 1m0s
[15:10:41.602] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:28:0)
[15:10:41.602] Timer tick, asking Buildbucket for the build status
[15:10:41.849] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:41.849] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:29:0) after 1m0s
[15:11:41.911] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:29:0)
[15:11:41.911] Timer tick, asking Buildbucket for the build status
[15:11:42.070] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:42.070] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:30:0) after 1m0s
[15:12:42.248] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:30:0)
[15:12:42.248] Timer tick, asking Buildbucket for the build status
[15:12:42.423] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:42.423] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:31:0) after 1m0s
[15:13:42.567] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:31:0)
[15:13:42.567] Timer tick, asking Buildbucket for the build status
[15:13:43.049] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:43.049] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:32:0) after 1m0s
[15:14:43.151] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:32:0)
[15:14:43.151] Timer tick, asking Buildbucket for the build status
[15:14:43.726] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:43.726] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:33:0) after 1m0s
[15:15:43.774] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:33:0)
[15:15:43.774] Timer tick, asking Buildbucket for the build status
[15:15:44.029] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:44.029] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:34:0) after 1m0s
[15:16:44.037] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:34:0)
[15:16:44.037] Timer tick, asking Buildbucket for the build status
[15:16:44.315] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:44.315] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:35:0) after 1m0s
[15:17:44.437] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:35:0)
[15:17:44.437] Timer tick, asking Buildbucket for the build status
[15:17:45.096] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:45.096] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:36:0) after 1m0s
[15:18:45.164] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:36:0)
[15:18:45.164] Timer tick, asking Buildbucket for the build status
[15:18:46.170] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:46.170] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:37:0) after 1m0s
[15:19:46.192] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:37:0)
[15:19:46.192] Timer tick, asking Buildbucket for the build status
[15:19:46.494] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:46.494] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:38:0) after 1m0s
[15:20:46.541] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:38:0)
[15:20:46.541] Timer tick, asking Buildbucket for the build status
[15:20:46.799] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:46.799] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:39:0) after 1m0s
[15:21:46.910] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:39:0)
[15:21:46.910] Timer tick, asking Buildbucket for the build status
[15:21:47.327] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:47.327] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:40:0) after 1m0s
[15:22:47.418] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:40:0)
[15:22:47.418] Timer tick, asking Buildbucket for the build status
[15:22:47.814] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:47.814] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:41:0) after 1m0s
[15:23:47.845] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:41:0)
[15:23:47.845] Timer tick, asking Buildbucket for the build status
[15:23:47.961] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:47.961] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:42:0) after 1m0s
[15:24:48.108] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:42:0)
[15:24:48.108] Timer tick, asking Buildbucket for the build status
[15:24:48.338] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:48.338] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:43:0) after 1m0s
[15:25:48.471] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:43:0)
[15:25:48.471] Timer tick, asking Buildbucket for the build status
[15:25:48.835] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:48.835] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:44:0) after 1m0s
[15:26:48.895] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:44:0)
[15:26:48.895] Timer tick, asking Buildbucket for the build status
[15:26:49.294] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:49.294] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:45:0) after 1m0s
[15:27:49.314] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:45:0)
[15:27:49.314] Timer tick, asking Buildbucket for the build status
[15:27:49.984] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:49.984] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:46:0) after 1m0s
[15:28:49.996] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:46:0)
[15:28:49.996] Timer tick, asking Buildbucket for the build status
[15:28:50.304] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:50.305] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:47:0) after 1m0s
[15:29:50.331] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:47:0)
[15:29:50.331] Timer tick, asking Buildbucket for the build status
[15:29:50.526] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:50.526] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:48:0) after 1m0s
[15:30:50.581] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:48:0)
[15:30:50.581] Timer tick, asking Buildbucket for the build status
[15:30:50.871] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:50.871] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:49:0) after 1m0s
[15:31:50.917] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:49:0)
[15:31:50.918] Timer tick, asking Buildbucket for the build status
[15:31:51.082] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:51.082] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:50:0) after 1m0s
[15:32:51.329] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:50:0)
[15:32:51.329] Timer tick, asking Buildbucket for the build status
[15:32:51.491] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:51.491] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:51:0) after 1m0s
[15:33:51.650] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:51:0)
[15:33:51.650] Timer tick, asking Buildbucket for the build status
[15:33:51.770] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:51.770] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:52:0) after 1m0s
[15:34:52.093] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:52:0)
[15:34:52.093] Timer tick, asking Buildbucket for the build status
[15:34:52.302] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:52.302] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:53:0) after 1m0s
[15:35:52.731] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:53:0)
[15:35:52.731] Timer tick, asking Buildbucket for the build status
[15:35:52.947] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:52.947] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:54:0) after 1m0s
[15:36:53.222] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:54:0)
[15:36:53.222] Timer tick, asking Buildbucket for the build status
[15:36:53.609] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:53.609] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:55:0) after 1m0s
[15:37:53.691] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:55:0)
[15:37:53.691] Timer tick, asking Buildbucket for the build status
[15:37:53.814] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:53.814] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:56:0) after 1m0s
[15:38:54.144] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:56:0)
[15:38:54.145] Timer tick, asking Buildbucket for the build status
[15:38:54.452] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.452] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:57:0) after 1m0s
[15:39:54.905] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:57:0)
[15:39:54.905] Timer tick, asking Buildbucket for the build status
[15:39:55.158] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:55.158] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:58:0) after 1m0s
[15:40:55.397] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:58:0)
[15:40:55.397] Timer tick, asking Buildbucket for the build status
[15:40:56.677] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:56.677] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:59:0) after 1m0s
[15:41:56.803] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:59:0)
[15:41:56.803] Timer tick, asking Buildbucket for the build status
[15:41:56.927] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:56.927] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:60:0) after 1m0s
[15:42:57.026] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:60:0)
[15:42:57.026] Timer tick, asking Buildbucket for the build status
[15:42:57.905] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:57.906] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:61:0) after 1m0s
[15:43:57.943] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:61:0)
[15:43:57.943] Timer tick, asking Buildbucket for the build status
[15:43:58.050] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:58.050] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:62:0) after 1m0s
[15:44:58.085] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:62:0)
[15:44:58.085] Timer tick, asking Buildbucket for the build status
[15:44:59.063] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:59.063] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:63:0) after 1m0s
[15:45:59.110] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:63:0)
[15:45:59.110] Timer tick, asking Buildbucket for the build status
[15:45:59.502] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:59.503] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:64:0) after 1m0s
[15:46:59.535] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:64:0)
[15:46:59.535] Timer tick, asking Buildbucket for the build status
[15:47:00.181] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:00.181] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:65:0) after 1m0s
[15:48:00.258] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:65:0)
[15:48:00.258] Timer tick, asking Buildbucket for the build status
[15:48:00.709] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:00.709] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:66:0) after 1m0s
[15:49:00.807] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:66:0)
[15:49:00.807] Timer tick, asking Buildbucket for the build status
[15:49:00.932] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:00.932] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:67:0) after 1m0s
[15:50:00.956] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:67:0)
[15:50:00.956] Timer tick, asking Buildbucket for the build status
[15:50:01.886] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:01.886] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:68:0) after 1m0s
[15:51:01.980] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:68:0)
[15:51:01.980] Timer tick, asking Buildbucket for the build status
[15:51:02.112] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:02.112] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:69:0) after 1m0s
[15:52:02.255] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:69:0)
[15:52:02.256] Timer tick, asking Buildbucket for the build status
[15:52:02.379] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:02.379] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:70:0) after 1m0s
[15:53:02.502] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:70:0)
[15:53:02.504] Timer tick, asking Buildbucket for the build status
[15:53:02.654] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:02.654] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:71:0) after 1m0s
[15:54:02.872] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:71:0)
[15:54:02.872] Timer tick, asking Buildbucket for the build status
[15:54:03.388] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:03.388] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:72:0) after 1m0s
[15:55:03.514] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:72:0)
[15:55:03.514] Timer tick, asking Buildbucket for the build status
[15:55:03.641] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:03.641] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:73:0) after 1m0s
[15:56:03.761] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:73:0)
[15:56:03.761] Timer tick, asking Buildbucket for the build status
[15:56:03.962] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:03.962] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:74:0) after 1m0s
[15:57:04.022] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:74:0)
[15:57:04.022] Timer tick, asking Buildbucket for the build status
[15:57:04.145] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:04.145] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:75:0) after 1m0s
[15:58:04.311] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:75:0)
[15:58:04.311] Timer tick, asking Buildbucket for the build status
[15:58:04.671] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:04.671] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:76:0) after 1m0s
[15:59:04.795] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:76:0)
[15:59:04.795] Timer tick, asking Buildbucket for the build status
[15:59:05.334] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:05.334] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:77:0) after 1m0s
[16:00:05.423] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:77:0)
[16:00:05.423] Timer tick, asking Buildbucket for the build status
[16:00:05.699] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:05.699] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:78:0) after 1m0s
[16:01:06.039] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:78:0)
[16:01:06.039] Timer tick, asking Buildbucket for the build status
[16:01:06.257] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:06.257] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:79:0) after 1m0s
[16:02:06.366] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:79:0)
[16:02:06.366] Timer tick, asking Buildbucket for the build status
[16:02:06.596] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:06.596] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:80:0) after 1m0s
[16:03:06.957] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:80:0)
[16:03:06.957] Timer tick, asking Buildbucket for the build status
[16:03:07.141] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:07.141] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:81:0) after 1m0s
[16:04:07.743] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:81:0)
[16:04:07.743] Timer tick, asking Buildbucket for the build status
[16:04:07.906] Build 8905049246574443728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:07.906] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:9070479313985391408:82:0) after 1m0s
[16:04:12.814] Received PubSub notification, asking Buildbucket for the build status
[16:04:13.018] Build 8905049246574443728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:04:13.018] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1565885051742694",
"created_by": "project:v8",
"created_ts": "1565880269417250",
"id": "8905049246574443728",
"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\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"title\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"title\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51\"}]}, \"bot_id\": \"luci-v8-ci-win10-1-ezr9\", \"branch\": \"refs/heads/master\", \"buildername\": \"V8 Blink Win\", \"buildnumber\": 67, \"got_angle_revision\": \"611663f4f61c7a98fcd64208c15d99be2da8740f\", \"got_cr_revision\": \"e001bb16ae37320669f82aa74f99dd4e8cfdd026\", \"got_cr_revision_cp\": \"refs/heads/master@{#687257}\", \"got_dawn_revision\": \"5f8a8aadb96b5ac0302836de219943178f240c20\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"got_revision_cp\": \"refs/heads/master@{#63215}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"got_v8_revision_cp\": \"refs/heads/master@{#63215}\", \"got_webrtc_revision\": \"7c6f74ab0344e9c6201de711d54026e9990b8e6c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28844}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.v8.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium_integration\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"299c8059a27256565d924a2e6c0c1cbf5d460d51\", \"swarm_hashes_refs/heads/master(at){#63215}_without_patch\": {\"blink_web_tests_exparchive\": \"2298e7fb1795ad3552bff9f66c8f48a06ecbc5be\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_9904f97ef32843c5a9965a05151a82a583242aafe74830087d6efccf8144076a_v2\", \"builder_c45cd84c3011deeeb1bb95553d013b3b691464b45620fcd454d65c09381ae99d_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-v8-ci-win10-1-ezr9\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4397-8786906\"], \"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": "1565880317193200",
"status": "COMPLETED",
"status_changed_ts": "1565885052351982",
"tags": [
"build_address:luci.v8.ci/V8 Blink Win/67",
"builder:V8 Blink Win",
"buildset:commit/git/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/299c8059a27256565d924a2e6c0c1cbf5d460d51",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070479313985391408",
"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/8905049246574443728/+/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:46ae8e47e6557810",
"user_agent:luci-scheduler"
],
"updated_ts": "1565885052364478",
"url": "https://ci.chromium.org/b/8905049246574443728",
"utcnow_ts": "1565885053002121"
}
}
[16:04:13.018] Invocation finished in 1h19m50.052760283s with status SUCCEEDED