[09:42:56.715] New invocation is queued and will start shortly
[09:42:58.005] Starting the invocation (attempt 1)
[09:42:58.038] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[09:42:58.038] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[09:42:58.038] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9111085719758020896",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra.git\",\"revision\":\"17b6114f174eb3ed2fc13700c4cc877be5d8a50b\"}}",
"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:9111085719758020896",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b",
"gitiles_ref:refs/heads/master"
]
}
[09:42:59.095] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1527154978103940",
"id": "8945655657639027632",
"lease_expiration_ts": "1527163978816480",
"lease_key": "1646588676",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"17b6114f174eb3ed2fc13700c4cc877be5d8a50b\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1527154979010720",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/2479",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9111085719758020896",
"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/2479",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8945655657639027632",
"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/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b",
"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/8945655657639027632/+/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:9111085719758020896",
"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:3daa593742540610",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1527154979010740",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2479",
"utcnow_ts": "1527154979082810"
}
}
[09:42:59.095] Task URL: https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2479
[09:42:59.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:2:0) after 1m0s
[09:43:59.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:2:0)
[09:43:59.118] Timer tick, asking Buildbucket for the build status
[09:43:59.443] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:59.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:3:0) after 1m0s
[09:44:59.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:3:0)
[09:44:59.478] Timer tick, asking Buildbucket for the build status
[09:45:00.471] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:00.472] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:4:0) after 1m0s
[09:46:00.548] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:4:0)
[09:46:00.549] Timer tick, asking Buildbucket for the build status
[09:46:00.628] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:00.628] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:5:0) after 1m0s
[09:47:00.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:5:0)
[09:47:00.680] Timer tick, asking Buildbucket for the build status
[09:47:00.768] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:00.768] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:6:0) after 1m0s
[09:48:00.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:6:0)
[09:48:00.847] Timer tick, asking Buildbucket for the build status
[09:48:00.944] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:00.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:7:0) after 1m0s
[09:49:01.024] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:7:0)
[09:49:01.024] Timer tick, asking Buildbucket for the build status
[09:49:01.120] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:01.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:8:0) after 1m0s
[09:50:01.327] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:8:0)
[09:50:01.327] Timer tick, asking Buildbucket for the build status
[09:50:01.467] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:01.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:9:0) after 1m0s
[09:51:01.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:9:0)
[09:51:01.593] Timer tick, asking Buildbucket for the build status
[09:51:01.675] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:01.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:10:0) after 1m0s
[09:52:01.775] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:10:0)
[09:52:01.775] Timer tick, asking Buildbucket for the build status
[09:52:01.856] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:01.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:11:0) after 1m0s
[09:53:01.974] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:11:0)
[09:53:01.974] Timer tick, asking Buildbucket for the build status
[09:53:02.064] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:02.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:12:0) after 1m0s
[09:54:02.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:12:0)
[09:54:02.193] Timer tick, asking Buildbucket for the build status
[09:54:02.273] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:02.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:13:0) after 1m0s
[09:55:02.324] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:13:0)
[09:55:02.324] Timer tick, asking Buildbucket for the build status
[09:55:02.483] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:02.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:14:0) after 1m0s
[09:56:02.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:14:0)
[09:56:02.507] Timer tick, asking Buildbucket for the build status
[09:56:02.656] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:02.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:15:0) after 1m0s
[09:57:02.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:15:0)
[09:57:02.784] Timer tick, asking Buildbucket for the build status
[09:57:02.992] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:02.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:16:0) after 1m0s
[09:58:03.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:16:0)
[09:58:03.111] Timer tick, asking Buildbucket for the build status
[09:58:03.256] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:03.256] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:17:0) after 1m0s
[09:59:03.338] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:17:0)
[09:59:03.338] Timer tick, asking Buildbucket for the build status
[09:59:03.434] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:03.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:18:0) after 1m0s
[10:00:03.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:18:0)
[10:00:03.498] Timer tick, asking Buildbucket for the build status
[10:00:03.684] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:03.684] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:19:0) after 1m0s
[10:01:03.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:19:0)
[10:01:03.810] Timer tick, asking Buildbucket for the build status
[10:01:03.946] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:03.946] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:20:0) after 1m0s
[10:02:04.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:20:0)
[10:02:04.069] Timer tick, asking Buildbucket for the build status
[10:02:04.169] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:04.169] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:21:0) after 1m0s
[10:03:04.344] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:21:0)
[10:03:04.344] Timer tick, asking Buildbucket for the build status
[10:03:04.526] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:04.526] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:22:0) after 1m0s
[10:04:04.648] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:22:0)
[10:04:04.648] Timer tick, asking Buildbucket for the build status
[10:04:04.744] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:04.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:23:0) after 1m0s
[10:05:04.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:23:0)
[10:05:04.888] Timer tick, asking Buildbucket for the build status
[10:05:04.968] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:04.968] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:24:0) after 1m0s
[10:06:05.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:24:0)
[10:06:05.106] Timer tick, asking Buildbucket for the build status
[10:06:05.255] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:05.255] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:25:0) after 1m0s
[10:07:05.338] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:25:0)
[10:07:05.338] Timer tick, asking Buildbucket for the build status
[10:07:05.483] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:05.483] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:26:0) after 1m0s
[10:08:05.545] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:26:0)
[10:08:05.545] Timer tick, asking Buildbucket for the build status
[10:08:05.652] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:05.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:27:0) after 1m0s
[10:09:05.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:27:0)
[10:09:05.776] Timer tick, asking Buildbucket for the build status
[10:09:05.955] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:05.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:28:0) after 1m0s
[10:10:06.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:28:0)
[10:10:06.067] Timer tick, asking Buildbucket for the build status
[10:10:06.194] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:06.194] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:29:0) after 1m0s
[10:11:06.232] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:29:0)
[10:11:06.232] Timer tick, asking Buildbucket for the build status
[10:11:06.358] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:06.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:30:0) after 1m0s
[10:12:06.560] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:30:0)
[10:12:06.560] Timer tick, asking Buildbucket for the build status
[10:12:06.674] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:06.674] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:31:0) after 1m0s
[10:13:06.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:31:0)
[10:13:06.759] Timer tick, asking Buildbucket for the build status
[10:13:06.894] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:06.894] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:32:0) after 1m0s
[10:14:07.022] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:32:0)
[10:14:07.022] Timer tick, asking Buildbucket for the build status
[10:14:07.176] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:07.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:33:0) after 1m0s
[10:15:07.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:33:0)
[10:15:07.297] Timer tick, asking Buildbucket for the build status
[10:15:07.445] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:07.445] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:34:0) after 1m0s
[10:16:07.572] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:34:0)
[10:16:07.572] Timer tick, asking Buildbucket for the build status
[10:16:07.663] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:07.663] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:35:0) after 1m0s
[10:17:07.731] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:35:0)
[10:17:07.731] Timer tick, asking Buildbucket for the build status
[10:17:07.839] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:07.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:36:0) after 1m0s
[10:18:07.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:36:0)
[10:18:07.963] Timer tick, asking Buildbucket for the build status
[10:18:08.147] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:08.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:37:0) after 1m0s
[10:19:08.223] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:37:0)
[10:19:08.223] Timer tick, asking Buildbucket for the build status
[10:19:08.331] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:08.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:38:0) after 1m0s
[10:20:08.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:38:0)
[10:20:08.390] Timer tick, asking Buildbucket for the build status
[10:20:08.525] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:08.525] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:39:0) after 1m0s
[10:21:08.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:39:0)
[10:21:08.595] Timer tick, asking Buildbucket for the build status
[10:21:08.756] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:08.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:40:0) after 1m0s
[10:22:08.928] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:40:0)
[10:22:08.928] Timer tick, asking Buildbucket for the build status
[10:22:09.050] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:09.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:41:0) after 1m0s
[10:23:09.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:41:0)
[10:23:09.116] Timer tick, asking Buildbucket for the build status
[10:23:09.254] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:09.255] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:42:0) after 1m0s
[10:24:09.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:42:0)
[10:24:09.384] Timer tick, asking Buildbucket for the build status
[10:24:09.600] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:09.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:43:0) after 1m0s
[10:25:09.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:43:0)
[10:25:09.698] Timer tick, asking Buildbucket for the build status
[10:25:09.851] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:09.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:44:0) after 1m0s
[10:26:09.928] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:44:0)
[10:26:09.928] Timer tick, asking Buildbucket for the build status
[10:26:10.038] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:10.038] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:45:0) after 1m0s
[10:27:10.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:45:0)
[10:27:10.103] Timer tick, asking Buildbucket for the build status
[10:27:10.292] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:10.292] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:46:0) after 1m0s
[10:28:10.527] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:46:0)
[10:28:10.527] Timer tick, asking Buildbucket for the build status
[10:28:10.728] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:10.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:47:0) after 1m0s
[10:29:10.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:47:0)
[10:29:10.860] Timer tick, asking Buildbucket for the build status
[10:29:10.976] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:10.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:48:0) after 1m0s
[10:30:11.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:48:0)
[10:30:11.099] Timer tick, asking Buildbucket for the build status
[10:30:11.246] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:11.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:49:0) after 1m0s
[10:31:11.382] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:49:0)
[10:31:11.382] Timer tick, asking Buildbucket for the build status
[10:31:11.485] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:11.485] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:50:0) after 1m0s
[10:32:11.551] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:50:0)
[10:32:11.551] Timer tick, asking Buildbucket for the build status
[10:32:11.670] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:11.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:51:0) after 1m0s
[10:33:11.774] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:51:0)
[10:33:11.774] Timer tick, asking Buildbucket for the build status
[10:33:11.855] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:11.855] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:52:0) after 1m0s
[10:34:11.925] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:52:0)
[10:34:11.925] Timer tick, asking Buildbucket for the build status
[10:34:12.055] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:12.055] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:53:0) after 1m0s
[10:35:12.085] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:53:0)
[10:35:12.085] Timer tick, asking Buildbucket for the build status
[10:35:12.173] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:12.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:54:0) after 1m0s
[10:36:12.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:54:0)
[10:36:12.287] Timer tick, asking Buildbucket for the build status
[10:36:12.397] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:12.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:55:0) after 1m0s
[10:37:12.467] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:55:0)
[10:37:12.467] Timer tick, asking Buildbucket for the build status
[10:37:12.563] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:12.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:56:0) after 1m0s
[10:38:12.619] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:56:0)
[10:38:12.619] Timer tick, asking Buildbucket for the build status
[10:38:13.102] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:13.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:57:0) after 1m0s
[10:39:13.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:57:0)
[10:39:13.195] Timer tick, asking Buildbucket for the build status
[10:39:13.714] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:13.714] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:58:0) after 1m0s
[10:40:13.824] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:58:0)
[10:40:13.824] Timer tick, asking Buildbucket for the build status
[10:40:14.228] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:14.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:59:0) after 1m0s
[10:41:14.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:59:0)
[10:41:14.313] Timer tick, asking Buildbucket for the build status
[10:41:14.729] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:14.730] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:60:0) after 1m0s
[10:42:14.751] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:60:0)
[10:42:14.751] Timer tick, asking Buildbucket for the build status
[10:42:15.078] Build 8945655657639027632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:15.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9111085719758020896:61:0) after 1m0s
[10:43:05.304] Received PubSub notification, asking Buildbucket for the build status
[10:43:05.347] Build 8945655657639027632: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[10:43:05.347] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1527158583939810",
"created_by": "user:luci-scheduler-dev@appspot.gserviceaccount.com",
"created_ts": "1527154978103940",
"id": "8945655657639027632",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra.git\", \"revision\": \"17b6114f174eb3ed2fc13700c4cc877be5d8a50b\"}}",
"project": "infra",
"result": "CANCELED",
"result_details_json": "{\"swarming\": {\"task_result\": {\"created_ts\": \"2018-05-24T09:42:58.436510\", \"name\": \"bb-8945655657639027632-infra-infra-continuous-win10-64\", \"task_id\": \"3daa593742540610\", \"tags\": [\"allow_milo:1\", \"build_address:luci.infra.ci/infra-continuous-win10-64/2479\", \"buildbucket_bucket:luci.infra.ci\", \"buildbucket_build_id:8945655657639027632\", \"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/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b\", \"cpu:x86-64\", \"gitiles_ref:refs/heads/master\", \"log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8945655657639027632/+/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:9111085719758020896\", \"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\": [\"3581-e1f2952\"], \"abandoned_ts\": \"2018-05-24T10:43:03.939810\", \"failure\": false, \"state\": \"EXPIRED\", \"modified_ts\": \"2018-05-24T10:43:03.939810\", \"user\": \"\", \"current_task_slice\": \"0\"}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "COMPLETED",
"status_changed_ts": "1527158584686620",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/2479",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9111085719758020896",
"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/2479",
"swarming_tag:buildbucket_bucket:luci.infra.ci",
"swarming_tag:buildbucket_build_id:8945655657639027632",
"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/+/17b6114f174eb3ed2fc13700c4cc877be5d8a50b",
"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/8945655657639027632/+/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:9111085719758020896",
"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:3daa593742540610",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1527158584687510",
"url": "https://luci-milo-dev.appspot.com/p/infra/builders/luci.infra.ci/infra-continuous-win10-64/2479",
"utcnow_ts": "1527158585336440"
}
}
[10:43:05.347] Invocation finished in 1h0m8.648113497s with status FAILED