[09:12:34.022] New invocation is queued and will start shortly
[09:12:35.574] Starting the invocation (attempt 1)
[09:12:35.646] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:12:35.699] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:12:35.699] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9082821377475662224",
"parameters_json": "{\"builder_name\":\"V8 Linux - full debug\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Linux - full debug",
"scheduler_invocation_id:9082821377475662224",
"scheduler_job_id:v8/V8 Linux - full debug",
"user_agent:luci-scheduler",
"buildset:commit/git/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"gitiles_ref:refs/heads/master"
]
}
[09:12:36.837] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "AUTO",
"created_by": "project:v8",
"created_ts": "1554109955879413",
"id": "8917391314862601264",
"parameters_json": "{\"builder_name\": \"V8 Linux - full debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554109956575735",
"tags": [
"build_address:luci.v8.ci/V8 Linux - full debug/7633",
"builder:V8 Linux - full debug",
"buildset:commit/git/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082821377475662224",
"scheduler_job_id:v8/V8 Linux - full debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8917391314862601264/+/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": "1554109956596761",
"url": "https://ci.chromium.org/b/8917391314862601264",
"utcnow_ts": "1554109956827247"
}
}
[09:12:36.837] Task URL: https://ci.chromium.org/b/8917391314862601264
[09:12:36.837] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:2:0) after 1m0s
[09:13:36.853] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:2:0)
[09:13:36.853] Timer tick, asking Buildbucket for the build status
[09:13:37.363] Build 8917391314862601264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:37.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:3:0) after 1m0s
[09:14:37.336] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:3:0)
[09:14:37.336] Timer tick, asking Buildbucket for the build status
[09:14:37.546] Build 8917391314862601264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:37.546] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:4:0) after 1m0s
[09:15:37.567] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:4:0)
[09:15:37.567] Timer tick, asking Buildbucket for the build status
[09:15:37.980] Build 8917391314862601264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:37.980] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:5:0) after 1m0s
[09:15:51.016] Received PubSub notification, asking Buildbucket for the build status
[09:15:51.288] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:38.023] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:5:0)
[09:16:38.023] Timer tick, asking Buildbucket for the build status
[09:16:38.179] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:38.179] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:7:0) after 1m0s
[09:17:38.195] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:7:0)
[09:17:38.195] Timer tick, asking Buildbucket for the build status
[09:17:38.427] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:38.427] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:8:0) after 1m0s
[09:18:38.405] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:8:0)
[09:18:38.405] Timer tick, asking Buildbucket for the build status
[09:18:38.705] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:38.705] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:9:0) after 1m0s
[09:19:38.725] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:9:0)
[09:19:38.725] Timer tick, asking Buildbucket for the build status
[09:19:38.806] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:38.806] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:10:0) after 1m0s
[09:20:38.823] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:10:0)
[09:20:38.823] Timer tick, asking Buildbucket for the build status
[09:20:38.926] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:38.926] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:11:0) after 1m0s
[09:21:38.950] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:11:0)
[09:21:38.950] Timer tick, asking Buildbucket for the build status
[09:21:39.013] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:39.013] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:12:0) after 1m0s
[09:22:39.030] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:12:0)
[09:22:39.030] Timer tick, asking Buildbucket for the build status
[09:22:39.502] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:39.502] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:13:0) after 1m0s
[09:23:39.522] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:13:0)
[09:23:39.522] Timer tick, asking Buildbucket for the build status
[09:23:39.722] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:39.722] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:14:0) after 1m0s
[09:24:39.740] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:14:0)
[09:24:39.740] Timer tick, asking Buildbucket for the build status
[09:24:39.825] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:39.825] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:15:0) after 1m0s
[09:25:39.855] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:15:0)
[09:25:39.855] Timer tick, asking Buildbucket for the build status
[09:25:39.998] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:39.998] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:16:0) after 1m0s
[09:26:39.976] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:16:0)
[09:26:39.976] Timer tick, asking Buildbucket for the build status
[09:26:40.322] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:40.322] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:17:0) after 1m0s
[09:27:40.308] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:17:0)
[09:27:40.308] Timer tick, asking Buildbucket for the build status
[09:27:40.487] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:40.488] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:18:0) after 1m0s
[09:28:40.477] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:18:0)
[09:28:40.477] Timer tick, asking Buildbucket for the build status
[09:28:40.602] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:40.602] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:19:0) after 1m0s
[09:29:40.620] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:19:0)
[09:29:40.620] Timer tick, asking Buildbucket for the build status
[09:29:40.757] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:40.757] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:20:0) after 1m0s
[09:30:40.728] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:20:0)
[09:30:40.728] Timer tick, asking Buildbucket for the build status
[09:30:40.823] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:40.823] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:21:0) after 1m0s
[09:31:40.831] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:21:0)
[09:31:40.831] Timer tick, asking Buildbucket for the build status
[09:31:40.940] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:40.940] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:22:0) after 1m0s
[09:32:40.966] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:22:0)
[09:32:40.966] Timer tick, asking Buildbucket for the build status
[09:32:41.316] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:41.316] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:23:0) after 1m0s
[09:33:41.369] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:23:0)
[09:33:41.369] Timer tick, asking Buildbucket for the build status
[09:33:41.488] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:41.488] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:24:0) after 1m0s
[09:34:41.508] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:24:0)
[09:34:41.508] Timer tick, asking Buildbucket for the build status
[09:34:41.597] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:41.597] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:25:0) after 1m0s
[09:35:41.614] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:25:0)
[09:35:41.614] Timer tick, asking Buildbucket for the build status
[09:35:42.267] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:42.267] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:26:0) after 1m0s
[09:36:42.288] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:26:0)
[09:36:42.288] Timer tick, asking Buildbucket for the build status
[09:36:42.606] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:42.606] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:27:0) after 1m0s
[09:37:42.605] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:27:0)
[09:37:42.605] Timer tick, asking Buildbucket for the build status
[09:37:42.873] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:42.873] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:28:0) after 1m0s
[09:38:42.883] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:28:0)
[09:38:42.883] Timer tick, asking Buildbucket for the build status
[09:38:43.025] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:43.025] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:29:0) after 1m0s
[09:39:43.049] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:29:0)
[09:39:43.049] Timer tick, asking Buildbucket for the build status
[09:39:43.304] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:43.304] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:30:0) after 1m0s
[09:40:43.322] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:30:0)
[09:40:43.322] Timer tick, asking Buildbucket for the build status
[09:40:43.457] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:43.457] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:31:0) after 1m0s
[09:41:43.443] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:31:0)
[09:41:43.443] Timer tick, asking Buildbucket for the build status
[09:41:44.004] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:44.004] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:32:0) after 1m0s
[09:42:43.978] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:32:0)
[09:42:43.978] Timer tick, asking Buildbucket for the build status
[09:42:44.224] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:44.224] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:33:0) after 1m0s
[09:43:44.242] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:33:0)
[09:43:44.242] Timer tick, asking Buildbucket for the build status
[09:43:44.354] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:44.354] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:34:0) after 1m0s
[09:44:44.397] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:34:0)
[09:44:44.397] Timer tick, asking Buildbucket for the build status
[09:44:44.754] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:44.754] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:35:0) after 1m0s
[09:45:44.773] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:35:0)
[09:45:44.773] Timer tick, asking Buildbucket for the build status
[09:45:44.853] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:44.853] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:36:0) after 1m0s
[09:46:44.880] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:36:0)
[09:46:44.880] Timer tick, asking Buildbucket for the build status
[09:46:45.094] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:45.094] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:37:0) after 1m0s
[09:47:45.175] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:37:0)
[09:47:45.175] Timer tick, asking Buildbucket for the build status
[09:47:45.427] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:45.428] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:38:0) after 1m0s
[09:48:45.449] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:38:0)
[09:48:45.449] Timer tick, asking Buildbucket for the build status
[09:48:45.890] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:45.890] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:39:0) after 1m0s
[09:49:45.882] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:39:0)
[09:49:45.882] Timer tick, asking Buildbucket for the build status
[09:49:45.992] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:45.992] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:40:0) after 1m0s
[09:50:46.016] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:40:0)
[09:50:46.016] Timer tick, asking Buildbucket for the build status
[09:50:46.198] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:46.198] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:41:0) after 1m0s
[09:51:46.218] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:41:0)
[09:51:46.218] Timer tick, asking Buildbucket for the build status
[09:51:46.275] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:46.275] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:42:0) after 1m0s
[09:52:46.299] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:42:0)
[09:52:46.299] Timer tick, asking Buildbucket for the build status
[09:52:46.364] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:46.364] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:43:0) after 1m0s
[09:53:46.387] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:43:0)
[09:53:46.387] Timer tick, asking Buildbucket for the build status
[09:53:46.470] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:46.470] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:44:0) after 1m0s
[09:54:46.488] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:44:0)
[09:54:46.488] Timer tick, asking Buildbucket for the build status
[09:54:46.607] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:46.607] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:45:0) after 1m0s
[09:55:46.596] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:45:0)
[09:55:46.596] Timer tick, asking Buildbucket for the build status
[09:55:46.771] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:46.771] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:46:0) after 1m0s
[09:56:46.792] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:46:0)
[09:56:46.792] Timer tick, asking Buildbucket for the build status
[09:56:47.034] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:47.034] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:47:0) after 1m0s
[09:57:47.029] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:47:0)
[09:57:47.029] Timer tick, asking Buildbucket for the build status
[09:57:47.287] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:47.287] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:48:0) after 1m0s
[09:58:47.309] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:48:0)
[09:58:47.309] Timer tick, asking Buildbucket for the build status
[09:58:47.434] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:47.434] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:49:0) after 1m0s
[09:59:47.451] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:49:0)
[09:59:47.451] Timer tick, asking Buildbucket for the build status
[09:59:47.808] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:47.808] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:50:0) after 1m0s
[10:00:47.826] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:50:0)
[10:00:47.826] Timer tick, asking Buildbucket for the build status
[10:00:48.076] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:48.076] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:51:0) after 1m0s
[10:01:48.123] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:51:0)
[10:01:48.123] Timer tick, asking Buildbucket for the build status
[10:01:48.266] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:48.266] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:52:0) after 1m0s
[10:02:48.286] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:52:0)
[10:02:48.286] Timer tick, asking Buildbucket for the build status
[10:02:48.973] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:48.973] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:53:0) after 1m0s
[10:03:48.994] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:53:0)
[10:03:48.994] Timer tick, asking Buildbucket for the build status
[10:03:49.162] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.162] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:54:0) after 1m0s
[10:04:49.185] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:54:0)
[10:04:49.185] Timer tick, asking Buildbucket for the build status
[10:04:49.719] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.719] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:55:0) after 1m0s
[10:05:49.710] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:55:0)
[10:05:49.710] Timer tick, asking Buildbucket for the build status
[10:05:49.890] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:49.890] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:56:0) after 1m0s
[10:06:49.891] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:56:0)
[10:06:49.891] Timer tick, asking Buildbucket for the build status
[10:06:50.150] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.150] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:57:0) after 1m0s
[10:07:50.154] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:57:0)
[10:07:50.154] Timer tick, asking Buildbucket for the build status
[10:07:50.408] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.408] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:58:0) after 1m0s
[10:08:50.429] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:58:0)
[10:08:50.430] Timer tick, asking Buildbucket for the build status
[10:08:50.536] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:50.536] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:59:0) after 1m0s
[10:09:50.557] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:59:0)
[10:09:50.557] Timer tick, asking Buildbucket for the build status
[10:09:50.759] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:50.759] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:60:0) after 1m0s
[10:10:50.779] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:60:0)
[10:10:50.779] Timer tick, asking Buildbucket for the build status
[10:10:50.842] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:50.842] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:61:0) after 1m0s
[10:11:50.883] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:61:0)
[10:11:50.883] Timer tick, asking Buildbucket for the build status
[10:11:50.964] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:50.964] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:62:0) after 1m0s
[10:12:50.973] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:62:0)
[10:12:50.973] Timer tick, asking Buildbucket for the build status
[10:12:51.132] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:51.133] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:63:0) after 1m0s
[10:13:51.156] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:63:0)
[10:13:51.156] Timer tick, asking Buildbucket for the build status
[10:13:51.249] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:51.249] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:64:0) after 1m0s
[10:14:51.230] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:64:0)
[10:14:51.230] Timer tick, asking Buildbucket for the build status
[10:14:51.373] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:51.373] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:65:0) after 1m0s
[10:15:51.363] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:65:0)
[10:15:51.363] Timer tick, asking Buildbucket for the build status
[10:15:51.440] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.440] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:66:0) after 1m0s
[10:16:51.459] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:66:0)
[10:16:51.459] Timer tick, asking Buildbucket for the build status
[10:16:51.573] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:51.573] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:67:0) after 1m0s
[10:17:51.578] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:67:0)
[10:17:51.578] Timer tick, asking Buildbucket for the build status
[10:17:51.766] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:51.766] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:68:0) after 1m0s
[10:18:51.809] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:68:0)
[10:18:51.809] Timer tick, asking Buildbucket for the build status
[10:18:52.058] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:52.058] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:69:0) after 1m0s
[10:19:52.077] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:69:0)
[10:19:52.077] Timer tick, asking Buildbucket for the build status
[10:19:52.655] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:52.655] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:70:0) after 1m0s
[10:20:52.677] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:70:0)
[10:20:52.677] Timer tick, asking Buildbucket for the build status
[10:20:52.995] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:52.995] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:71:0) after 1m0s
[10:21:52.964] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:71:0)
[10:21:52.964] Timer tick, asking Buildbucket for the build status
[10:21:53.145] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:53.145] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:72:0) after 1m0s
[10:22:53.166] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:72:0)
[10:22:53.166] Timer tick, asking Buildbucket for the build status
[10:22:53.360] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:53.360] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:73:0) after 1m0s
[10:23:53.378] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:73:0)
[10:23:53.378] Timer tick, asking Buildbucket for the build status
[10:23:53.914] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:53.914] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:74:0) after 1m0s
[10:24:53.934] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:74:0)
[10:24:53.934] Timer tick, asking Buildbucket for the build status
[10:24:54.012] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:54.012] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:75:0) after 1m0s
[10:25:53.983] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:75:0)
[10:25:53.983] Timer tick, asking Buildbucket for the build status
[10:25:54.112] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:54.112] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:76:0) after 1m0s
[10:26:54.112] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:76:0)
[10:26:54.112] Timer tick, asking Buildbucket for the build status
[10:26:54.178] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:54.178] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:77:0) after 1m0s
[10:27:54.198] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:77:0)
[10:27:54.198] Timer tick, asking Buildbucket for the build status
[10:27:54.326] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:54.326] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:78:0) after 1m0s
[10:28:54.345] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:78:0)
[10:28:54.345] Timer tick, asking Buildbucket for the build status
[10:28:56.226] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:56.226] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:79:0) after 1m0s
[10:29:56.202] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:79:0)
[10:29:56.202] Timer tick, asking Buildbucket for the build status
[10:29:56.469] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:56.469] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:80:0) after 1m0s
[10:30:56.487] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:80:0)
[10:30:56.487] Timer tick, asking Buildbucket for the build status
[10:30:56.722] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:56.722] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:81:0) after 1m0s
[10:31:56.752] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:81:0)
[10:31:56.752] Timer tick, asking Buildbucket for the build status
[10:31:56.821] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:56.821] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:82:0) after 1m0s
[10:32:56.810] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:82:0)
[10:32:56.810] Timer tick, asking Buildbucket for the build status
[10:32:57.031] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:57.031] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:83:0) after 1m0s
[10:33:57.037] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:83:0)
[10:33:57.037] Timer tick, asking Buildbucket for the build status
[10:33:57.499] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:57.499] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:84:0) after 1m0s
[10:34:57.471] Handling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:84:0)
[10:34:57.471] Timer tick, asking Buildbucket for the build status
[10:34:57.569] Build 8917391314862601264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:57.569] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux - full debug:9082821377475662224:85:0) after 1m0s
[10:35:55.751] Received PubSub notification, asking Buildbucket for the build status
[10:35:56.100] Build 8917391314862601264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:35:56.100] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "AUTO",
"completed_ts": "1554114954113756",
"created_by": "project:v8",
"created_ts": "1554109955879413",
"id": "8917391314862601264",
"parameters_json": "{\"builder_name\": \"V8 Linux - full debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm352-c4\", \"branch\": \"refs/heads/master\", \"build_config\": \"Debug\", \"buildername\": \"V8 Linux - full debug\", \"buildnumber\": 7633, \"got_revision\": \"2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575\", \"got_revision_cp\": \"refs/heads/master@{#60540}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.v8\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_013b67fc6a24ef38ce64919e0efdbdf1b0fd7949fecc48a6ab253e9a289f8b16_v2\", \"builder_0756c5b6dc276f8ca56bf3d27eff9e06c4e026b9904c870a497a8be050bbc4b4_v2\", \"builder_0fa98dd0982ff1e4f88468028225c03385049db9a4bbed57895f50f02b3e716e_v2\", \"builder_225a2cec8b7772d06155bfd792996ef72e144f6dde2f4fa5f1abbf24f9a9df1b_v2\", \"builder_3f9034e60101d3695fe7ca470f378cea7bcb75392ebaa67f43e146decc03c34e_v2\", \"builder_4180ee241bf03a2f704e3869d8835fa2524e2f45776cd7decb643e8031343e0b_v2\", \"builder_59f28fbb43ccf45a12913e9755409e0ed041b0f00bbe0d5138ca936d6d11aa7c_v2\", \"builder_5a7fdfafde4a1a2f1232842a5a22515ef4e41636739b1cabcddb933de133a078_v2\", \"builder_6505571aa603deb67a1fe1014af43647afd42d39e48a173373f63a67ae763ab5_v2\", \"builder_6922afcfe3a504eb393c38397a601e767bf7b37d87a307b599543476023061b5_v2\", \"builder_75e3add2abe6a2d0e3baf7d0dacc3b41b5b2e41e4e17207fa2103c3729e02a5f_v2\", \"builder_811766b64b8273b43813ff3d29ac40f0b8fd1070074ed1ae4351c12b486595af_v2\", \"builder_84671296ec1ed8a9c1442b402b2805dc5345a6d03ef5bd58708af00cb70779b0_v2\", \"builder_8c8bfe4a177720f3cdc3d8a926a6526529c5bc999e5099e1156d8ad583c8677f_v2\", \"builder_a80f5e092292a0eeac8af219217427d7ea436f4845bc055ea4a436d664d3aaae_v2\", \"builder_b4460c21c18938b906540774294f2dad1256b696afaec8ed5adb05ffba120505_v2\", \"builder_d1c3e0d90e9b53ef779121b15b0a0fa8b5382e4ee5547848a75659bd6254b233_v2\", \"builder_deefd27b3a613dd2e5c4871b28d5ccb6064afe0b497d1c5e0d1faf83735a9792_v2\", \"builder_fd5b6657f1d3a2f1a487e0b5530f22908da59be7cac27acc2fc249a9d5248335_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"swarm352-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554110150417230",
"status": "COMPLETED",
"status_changed_ts": "1554114955415524",
"tags": [
"build_address:luci.v8.ci/V8 Linux - full debug/7633",
"builder:V8 Linux - full debug",
"buildset:commit/git/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/2bcbc4b632b4ffa8dfebd7722e0d12a23e14d575",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082821377475662224",
"scheduler_job_id:v8/V8 Linux - full debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8917391314862601264/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43f0fd8b49df0b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1554114955499108",
"url": "https://ci.chromium.org/b/8917391314862601264",
"utcnow_ts": "1554114956085806"
}
}
[10:35:56.100] Invocation finished in 1h23m22.097065329s with status SUCCEEDED