[14:37:10.169] New invocation is queued and will start shortly
[14:37:11.466] Starting the invocation (attempt 1)
[14:37:11.503] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[14:37:11.520] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[14:37:11.521] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9111067208769749760",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra.git\",\"revision\":\"6ccc7083452adb630ba06450334881d5d4a1341b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9111067208769749760",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b",
"gitiles_ref:refs/heads/master"
]
}
[14:37:12.620] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1527172631596660",
"id": "8945637146609847872",
"lease_expiration_ts": "1527181632347750",
"lease_key": "399575087",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"6ccc7083452adb630ba06450334881d5d4a1341b\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1527172632523770",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/2483",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9111067208769749760",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:Chrome",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:allow_milo:1",
"swarming_tag:build_address:luci.infra.ci/infra-continuous-win10-64/2483",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8945637146609847872",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:31208890f23bdb2fc5a18d6f2e1d18355d60a3a4",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8945637146609847872/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:Chrome",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:infra_continuous_luci",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9111067208769749760",
"swarming_tag:scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_tag:service_account:adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:3dab66963d7b8710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1527172632523780",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2483",
"utcnow_ts": "1527172632608560"
}
}
[14:37:12.620] Task URL: https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2483
[14:37:12.620] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:2:0) after 1m0s
[14:38:12.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:2:0)
[14:38:12.708] Timer tick, asking Buildbucket for the build status
[14:38:12.840] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:12.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:3:0) after 1m0s
[14:39:12.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:3:0)
[14:39:12.959] Timer tick, asking Buildbucket for the build status
[14:39:13.062] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:13.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:4:0) after 1m0s
[14:40:13.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:4:0)
[14:40:13.183] Timer tick, asking Buildbucket for the build status
[14:40:13.248] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:13.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:5:0) after 1m0s
[14:41:13.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:5:0)
[14:41:13.367] Timer tick, asking Buildbucket for the build status
[14:41:13.539] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:13.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:6:0) after 1m0s
[14:42:13.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:6:0)
[14:42:13.578] Timer tick, asking Buildbucket for the build status
[14:42:13.660] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:13.660] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:7:0) after 1m0s
[14:43:13.772] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:7:0)
[14:43:13.772] Timer tick, asking Buildbucket for the build status
[14:43:13.854] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:13.854] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:8:0) after 1m0s
[14:44:13.919] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:8:0)
[14:44:13.919] Timer tick, asking Buildbucket for the build status
[14:44:14.041] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:14.041] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:9:0) after 1m0s
[14:45:14.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:9:0)
[14:45:14.094] Timer tick, asking Buildbucket for the build status
[14:45:14.194] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:14.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:10:0) after 1m0s
[14:46:14.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:10:0)
[14:46:14.229] Timer tick, asking Buildbucket for the build status
[14:46:14.370] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:14.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:11:0) after 1m0s
[14:47:14.481] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:11:0)
[14:47:14.482] Timer tick, asking Buildbucket for the build status
[14:47:14.641] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:47:14.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:12:0) after 1m0s
[14:48:14.760] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:12:0)
[14:48:14.760] Timer tick, asking Buildbucket for the build status
[14:48:14.855] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:48:14.855] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:13:0) after 1m0s
[14:49:14.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:13:0)
[14:49:14.973] Timer tick, asking Buildbucket for the build status
[14:49:15.042] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:49:15.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:14:0) after 1m0s
[14:50:15.144] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:14:0)
[14:50:15.144] Timer tick, asking Buildbucket for the build status
[14:50:15.267] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:50:15.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:15:0) after 1m0s
[14:51:15.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:15:0)
[14:51:15.287] Timer tick, asking Buildbucket for the build status
[14:51:15.360] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:51:15.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:16:0) after 1m0s
[14:52:15.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:16:0)
[14:52:15.380] Timer tick, asking Buildbucket for the build status
[14:52:15.561] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:52:15.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:17:0) after 1m0s
[14:53:15.585] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:17:0)
[14:53:15.585] Timer tick, asking Buildbucket for the build status
[14:53:15.652] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:53:15.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:18:0) after 1m0s
[14:54:15.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:18:0)
[14:54:15.675] Timer tick, asking Buildbucket for the build status
[14:54:15.979] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:54:15.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:19:0) after 1m0s
[14:55:16.053] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:19:0)
[14:55:16.053] Timer tick, asking Buildbucket for the build status
[14:55:16.396] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:55:16.396] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:20:0) after 1m0s
[14:56:16.575] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:20:0)
[14:56:16.575] Timer tick, asking Buildbucket for the build status
[14:56:16.924] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:56:16.924] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:21:0) after 1m0s
[14:57:17.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:21:0)
[14:57:17.051] Timer tick, asking Buildbucket for the build status
[14:57:17.434] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:57:17.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:22:0) after 1m0s
[14:58:17.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:22:0)
[14:58:17.521] Timer tick, asking Buildbucket for the build status
[14:58:17.981] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:58:17.981] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:23:0) after 1m0s
[14:59:18.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:23:0)
[14:59:18.156] Timer tick, asking Buildbucket for the build status
[14:59:22.052] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:59:22.052] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:24:0) after 1m0s
[15:00:22.070] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:24:0)
[15:00:22.070] Timer tick, asking Buildbucket for the build status
[15:00:22.499] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:00:22.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:25:0) after 1m0s
[15:01:22.538] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:25:0)
[15:01:22.538] Timer tick, asking Buildbucket for the build status
[15:01:22.912] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:01:22.912] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:26:0) after 1m0s
[15:02:22.948] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:26:0)
[15:02:22.948] Timer tick, asking Buildbucket for the build status
[15:02:23.391] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:02:23.391] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:27:0) after 1m0s
[15:03:23.438] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:27:0)
[15:03:23.438] Timer tick, asking Buildbucket for the build status
[15:03:23.852] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:03:23.852] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:28:0) after 1m0s
[15:04:23.939] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:28:0)
[15:04:23.939] Timer tick, asking Buildbucket for the build status
[15:04:24.287] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:04:24.287] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:29:0) after 1m0s
[15:05:24.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:29:0)
[15:05:24.343] Timer tick, asking Buildbucket for the build status
[15:05:24.806] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:05:24.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:30:0) after 1m0s
[15:06:24.872] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:30:0)
[15:06:24.872] Timer tick, asking Buildbucket for the build status
[15:06:25.243] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:06:25.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:31:0) after 1m0s
[15:07:25.281] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:31:0)
[15:07:25.281] Timer tick, asking Buildbucket for the build status
[15:07:25.562] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:07:25.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:32:0) after 1m0s
[15:08:25.683] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:32:0)
[15:08:25.683] Timer tick, asking Buildbucket for the build status
[15:08:26.130] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:08:26.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:33:0) after 1m0s
[15:09:26.419] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:33:0)
[15:09:26.419] Timer tick, asking Buildbucket for the build status
[15:09:26.617] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:09:26.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:34:0) after 1m0s
[15:10:26.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:34:0)
[15:10:26.696] Timer tick, asking Buildbucket for the build status
[15:10:33.050] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:10:33.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:35:0) after 1m0s
[15:11:33.171] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:35:0)
[15:11:33.171] Timer tick, asking Buildbucket for the build status
[15:11:33.281] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:11:33.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:36:0) after 1m0s
[15:12:33.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:36:0)
[15:12:33.415] Timer tick, asking Buildbucket for the build status
[15:12:33.858] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:12:33.858] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:37:0) after 1m0s
[15:13:33.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:37:0)
[15:13:33.983] Timer tick, asking Buildbucket for the build status
[15:13:34.327] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:34.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:38:0) after 1m0s
[15:14:34.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:38:0)
[15:14:34.427] Timer tick, asking Buildbucket for the build status
[15:14:34.788] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:34.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:39:0) after 1m0s
[15:15:34.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:39:0)
[15:15:34.914] Timer tick, asking Buildbucket for the build status
[15:15:35.286] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:35.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:40:0) after 1m0s
[15:16:35.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:40:0)
[15:16:35.355] Timer tick, asking Buildbucket for the build status
[15:16:35.687] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:35.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:41:0) after 1m0s
[15:17:35.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:41:0)
[15:17:35.807] Timer tick, asking Buildbucket for the build status
[15:17:36.211] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:17:36.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:42:0) after 1m0s
[15:18:36.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:42:0)
[15:18:36.302] Timer tick, asking Buildbucket for the build status
[15:18:36.689] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:18:36.689] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:43:0) after 1m0s
[15:19:36.819] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:43:0)
[15:19:36.819] Timer tick, asking Buildbucket for the build status
[15:19:37.172] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:19:37.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:44:0) after 1m0s
[15:20:37.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:44:0)
[15:20:37.298] Timer tick, asking Buildbucket for the build status
[15:20:37.737] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:20:37.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:45:0) after 1m0s
[15:21:37.858] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:45:0)
[15:21:37.858] Timer tick, asking Buildbucket for the build status
[15:21:38.220] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:21:38.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:46:0) after 1m0s
[15:22:38.348] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:46:0)
[15:22:38.348] Timer tick, asking Buildbucket for the build status
[15:22:38.667] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:22:38.667] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:47:0) after 1m0s
[15:23:38.772] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:47:0)
[15:23:38.772] Timer tick, asking Buildbucket for the build status
[15:23:39.290] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:23:39.290] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:48:0) after 1m0s
[15:24:39.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:48:0)
[15:24:39.391] Timer tick, asking Buildbucket for the build status
[15:24:39.698] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:24:39.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:49:0) after 1m0s
[15:25:39.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:49:0)
[15:25:39.777] Timer tick, asking Buildbucket for the build status
[15:25:39.954] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:25:39.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:50:0) after 1m0s
[15:26:40.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:50:0)
[15:26:40.206] Timer tick, asking Buildbucket for the build status
[15:26:40.427] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:26:40.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:51:0) after 1m0s
[15:27:40.474] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:51:0)
[15:27:40.474] Timer tick, asking Buildbucket for the build status
[15:27:40.635] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:27:40.635] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:52:0) after 1m0s
[15:28:40.728] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:52:0)
[15:28:40.728] Timer tick, asking Buildbucket for the build status
[15:28:40.836] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:40.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:53:0) after 1m0s
[15:29:40.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:53:0)
[15:29:40.880] Timer tick, asking Buildbucket for the build status
[15:29:40.976] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:29:40.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:54:0) after 1m0s
[15:30:41.262] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:54:0)
[15:30:41.263] Timer tick, asking Buildbucket for the build status
[15:30:41.424] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:30:41.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:55:0) after 1m0s
[15:31:41.470] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:55:0)
[15:31:41.470] Timer tick, asking Buildbucket for the build status
[15:31:41.574] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:41.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:56:0) after 1m0s
[15:32:41.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:56:0)
[15:32:41.622] Timer tick, asking Buildbucket for the build status
[15:32:41.773] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:41.773] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:57:0) after 1m0s
[15:33:41.871] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:57:0)
[15:33:41.871] Timer tick, asking Buildbucket for the build status
[15:33:41.988] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:33:41.988] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:58:0) after 1m0s
[15:34:42.132] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:58:0)
[15:34:42.132] Timer tick, asking Buildbucket for the build status
[15:34:42.320] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:34:42.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:59:0) after 1m0s
[15:35:42.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:59:0)
[15:35:42.446] Timer tick, asking Buildbucket for the build status
[15:35:42.543] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:42.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:60:0) after 1m0s
[15:36:42.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:60:0)
[15:36:42.597] Timer tick, asking Buildbucket for the build status
[15:36:42.738] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:42.738] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:61:0) after 1m0s
[15:37:42.812] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:61:0)
[15:37:42.812] Timer tick, asking Buildbucket for the build status
[15:37:42.995] Build 8945637146609847872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:42.995] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111067208769749760:62:0) after 1m0s
[15:38:02.345] Received PubSub notification, asking Buildbucket for the build status
[15:38:02.440] Build 8945637146609847872: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[15:38:02.440] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1527176280822840",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1527172631596660",
"id": "8945637146609847872",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"6ccc7083452adb630ba06450334881d5d4a1341b\"}}",
"project": "infra",
"result": "CANCELED",
"result_details_json": "{\"swarming\": {\"task_result\": {\"created_ts\": \"2018-05-24T14:37:11.950190\", \"name\": \"bb-8945637146609847872-infra-infra-continuous-win10-64\", \"task_id\": \"3dab66963d7b8710\", \"tags\": [\"allow_milo:1\", \"build_address:luci.infra.ci/infra-continuous-win10-64/2483\", \"buildbucket_bucket:luci.infra.ci\", \"buildbucket_build_id:8945637146609847872\", \"buildbucket_hostname:cr-buildbucket-dev.appspot.com\", \"buildbucket_template_canary:0\", \"buildbucket_template_revision:31208890f23bdb2fc5a18d6f2e1d18355d60a3a4\", \"builder:infra-continuous-win10-64\", \"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b\", \"cpu:x86-64\", \"gitiles_ref:refs/heads/master\", \"log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8945637146609847872/+/annotations\", \"luci_project:infra\", \"os:Windows-10\", \"pool:Chrome\", \"priority:30\", \"recipe_name:infra_continuous_luci\", \"recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra\", \"scheduler_invocation_id:9111067208769749760\", \"scheduler_job_id:infra/infra-continuous-win10-64\", \"service_account:adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com\", \"user:None\", \"user_agent:luci-scheduler-dev\"], \"internal_failure\": false, \"server_versions\": [\"3582-d4649a7\"], \"abandoned_ts\": \"2018-05-24T15:38:00.822840\", \"failure\": false, \"state\": \"EXPIRED\", \"modified_ts\": \"2018-05-24T15:38:00.822840\", \"user\": \"\", \"current_task_slice\": \"0\"}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "COMPLETED",
"status_changed_ts": "1527176281524790",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/2483",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9111067208769749760",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:Chrome",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:allow_milo:1",
"swarming_tag:build_address:luci.infra.ci/infra-continuous-win10-64/2483",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8945637146609847872",
"swarming_tag:buildbucket_hostname:cr-buildbucket-dev.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:31208890f23bdb2fc5a18d6f2e1d18355d60a3a4",
"swarming_tag:builder:infra-continuous-win10-64",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/6ccc7083452adb630ba06450334881d5d4a1341b",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8945637146609847872/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:Chrome",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:infra_continuous_luci",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9111067208769749760",
"swarming_tag:scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_tag:service_account:adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler-dev",
"swarming_task_id:3dab66963d7b8710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1527176281527070",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2483",
"utcnow_ts": "1527176282424310"
}
}
[15:38:02.440] Invocation finished in 1h0m52.287243701s with status FAILED