[02:17:59.815] New invocation is queued and will start shortly
[02:18:01.304] Starting the invocation (attempt 1)
[02:18:01.350] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:18:01.350] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:18:01.350] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9067898960514944064",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"8fc70df4cab3589883092aef9ff6e43b9309cea2\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@8fc70df4cab3589883092aef9ff6e43b9309cea2\",\"title\":\"8fc70df4cab3589883092aef9ff6e43b9309cea2\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9067898960514944064",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"gitiles_ref:refs/heads/master"
]
}
[02:18:02.449] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1568341081603529",
"id": "8902468897974866416",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"title\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568341082259151",
"tags": [
"builder:win",
"buildset:commit/git/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067898960514944064",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8902468897974866416/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1568341082259278",
"url": "https://ci.chromium.org/b/8902468897974866416",
"utcnow_ts": "1568341082441529"
}
}
[02:18:02.449] Task URL: https://ci.chromium.org/b/8902468897974866416
[02:18:02.449] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:2:0) after 1m0s
[02:19:02.505] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:2:0)
[02:19:02.505] Timer tick, asking Buildbucket for the build status
[02:19:02.652] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:02.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:3:0) after 1m0s
[02:20:05.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:3:0)
[02:20:05.091] Timer tick, asking Buildbucket for the build status
[02:20:05.314] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:05.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:4:0) after 1m0s
[02:21:05.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:4:0)
[02:21:05.392] Timer tick, asking Buildbucket for the build status
[02:21:05.486] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:05.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:5:0) after 1m0s
[02:22:05.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:5:0)
[02:22:05.557] Timer tick, asking Buildbucket for the build status
[02:22:05.928] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:05.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:6:0) after 1m0s
[02:23:05.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:6:0)
[02:23:05.983] Timer tick, asking Buildbucket for the build status
[02:23:06.190] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:06.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:7:0) after 1m0s
[02:24:06.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:7:0)
[02:24:06.172] Timer tick, asking Buildbucket for the build status
[02:24:06.770] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:06.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:8:0) after 1m0s
[02:25:06.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:8:0)
[02:25:06.796] Timer tick, asking Buildbucket for the build status
[02:25:06.945] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:06.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:9:0) after 1m0s
[02:26:07.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:9:0)
[02:26:07.035] Timer tick, asking Buildbucket for the build status
[02:26:07.102] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:07.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:10:0) after 1m0s
[02:27:07.130] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:10:0)
[02:27:07.130] Timer tick, asking Buildbucket for the build status
[02:27:07.561] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:07.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:11:0) after 1m0s
[02:28:07.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:11:0)
[02:28:07.610] Timer tick, asking Buildbucket for the build status
[02:28:08.035] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:08.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:12:0) after 1m0s
[02:29:08.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:12:0)
[02:29:08.154] Timer tick, asking Buildbucket for the build status
[02:29:08.349] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:08.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:13:0) after 1m0s
[02:30:08.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:13:0)
[02:30:08.381] Timer tick, asking Buildbucket for the build status
[02:30:08.625] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:08.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:14:0) after 1m0s
[02:31:08.682] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:14:0)
[02:31:08.682] Timer tick, asking Buildbucket for the build status
[02:31:08.884] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:08.884] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:15:0) after 1m0s
[02:32:08.893] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:15:0)
[02:32:08.893] Timer tick, asking Buildbucket for the build status
[02:32:09.306] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:09.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:16:0) after 1m0s
[02:33:09.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:16:0)
[02:33:09.392] Timer tick, asking Buildbucket for the build status
[02:33:09.633] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:09.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:17:0) after 1m0s
[02:34:09.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:17:0)
[02:34:09.653] Timer tick, asking Buildbucket for the build status
[02:34:09.876] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:09.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:18:0) after 1m0s
[02:35:09.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:18:0)
[02:35:09.969] Timer tick, asking Buildbucket for the build status
[02:35:10.112] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:10.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:19:0) after 1m0s
[02:36:10.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:19:0)
[02:36:10.241] Timer tick, asking Buildbucket for the build status
[02:36:10.427] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:10.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:20:0) after 1m0s
[02:37:10.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:20:0)
[02:37:10.477] Timer tick, asking Buildbucket for the build status
[02:37:10.600] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:10.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:21:0) after 1m0s
[02:38:10.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:21:0)
[02:38:10.657] Timer tick, asking Buildbucket for the build status
[02:38:10.799] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:10.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:22:0) after 1m0s
[02:39:10.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:22:0)
[02:39:10.955] Timer tick, asking Buildbucket for the build status
[02:39:11.086] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:11.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:23:0) after 1m0s
[02:40:11.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:23:0)
[02:40:11.104] Timer tick, asking Buildbucket for the build status
[02:40:13.932] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:13.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:24:0) after 1m0s
[02:41:13.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:24:0)
[02:41:13.976] Timer tick, asking Buildbucket for the build status
[02:41:14.197] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:14.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:25:0) after 1m0s
[02:42:14.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:25:0)
[02:42:14.288] Timer tick, asking Buildbucket for the build status
[02:42:14.569] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:14.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:26:0) after 1m0s
[02:43:14.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:26:0)
[02:43:14.588] Timer tick, asking Buildbucket for the build status
[02:43:14.693] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:14.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:27:0) after 1m0s
[02:44:14.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:27:0)
[02:44:14.832] Timer tick, asking Buildbucket for the build status
[02:44:14.957] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:14.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:28:0) after 1m0s
[02:45:14.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:28:0)
[02:45:14.953] Timer tick, asking Buildbucket for the build status
[02:45:15.081] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:15.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:29:0) after 1m0s
[02:46:15.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:29:0)
[02:46:15.159] Timer tick, asking Buildbucket for the build status
[02:46:15.314] Build 8902468897974866416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:15.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:30:0) after 1m0s
[02:46:54.652] Received PubSub notification, asking Buildbucket for the build status
[02:46:54.762] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:15.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:30:0)
[02:47:15.391] Timer tick, asking Buildbucket for the build status
[02:47:15.488] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:15.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:32:0) after 1m0s
[02:48:15.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:32:0)
[02:48:15.570] Timer tick, asking Buildbucket for the build status
[02:48:16.111] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:16.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:33:0) after 1m0s
[02:49:16.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:33:0)
[02:49:16.192] Timer tick, asking Buildbucket for the build status
[02:49:16.356] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:16.356] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:34:0) after 1m0s
[02:50:16.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:34:0)
[02:50:16.534] Timer tick, asking Buildbucket for the build status
[02:50:16.736] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:16.736] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:35:0) after 1m0s
[02:51:16.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:35:0)
[02:51:16.911] Timer tick, asking Buildbucket for the build status
[02:51:17.266] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:17.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:36:0) after 1m0s
[02:52:17.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:36:0)
[02:52:17.288] Timer tick, asking Buildbucket for the build status
[02:52:18.414] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:18.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:37:0) after 1m0s
[02:53:18.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:37:0)
[02:53:18.437] Timer tick, asking Buildbucket for the build status
[02:53:18.709] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:18.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:38:0) after 1m0s
[02:54:18.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:38:0)
[02:54:18.732] Timer tick, asking Buildbucket for the build status
[02:54:19.038] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:19.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:39:0) after 1m0s
[02:55:19.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:39:0)
[02:55:19.092] Timer tick, asking Buildbucket for the build status
[02:55:19.914] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:19.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:40:0) after 1m0s
[02:56:19.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:40:0)
[02:56:19.944] Timer tick, asking Buildbucket for the build status
[02:56:20.083] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:20.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:41:0) after 1m0s
[02:57:20.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:41:0)
[02:57:20.182] Timer tick, asking Buildbucket for the build status
[02:57:20.337] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:20.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:42:0) after 1m0s
[02:58:20.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:42:0)
[02:58:20.364] Timer tick, asking Buildbucket for the build status
[02:58:20.500] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:20.500] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:43:0) after 1m0s
[02:59:20.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:43:0)
[02:59:20.616] Timer tick, asking Buildbucket for the build status
[02:59:20.928] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:20.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:44:0) after 1m0s
[03:00:20.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:44:0)
[03:00:20.951] Timer tick, asking Buildbucket for the build status
[03:00:24.639] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:24.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:45:0) after 1m0s
[03:01:24.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:45:0)
[03:01:24.743] Timer tick, asking Buildbucket for the build status
[03:01:25.706] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:25.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:46:0) after 1m0s
[03:02:25.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:46:0)
[03:02:25.732] Timer tick, asking Buildbucket for the build status
[03:02:26.105] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:26.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:47:0) after 1m0s
[03:03:26.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:47:0)
[03:03:26.119] Timer tick, asking Buildbucket for the build status
[03:03:26.385] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:26.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:48:0) after 1m0s
[03:04:26.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:48:0)
[03:04:26.411] Timer tick, asking Buildbucket for the build status
[03:04:26.644] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:26.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:49:0) after 1m0s
[03:05:26.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:49:0)
[03:05:26.700] Timer tick, asking Buildbucket for the build status
[03:05:26.926] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:26.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:50:0) after 1m0s
[03:06:26.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:50:0)
[03:06:26.952] Timer tick, asking Buildbucket for the build status
[03:06:27.145] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:51:0) after 1m0s
[03:07:27.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:51:0)
[03:07:27.184] Timer tick, asking Buildbucket for the build status
[03:07:27.934] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:27.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:52:0) after 1m0s
[03:08:27.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:52:0)
[03:08:27.971] Timer tick, asking Buildbucket for the build status
[03:08:28.172] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:28.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:53:0) after 1m0s
[03:09:28.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:53:0)
[03:09:28.193] Timer tick, asking Buildbucket for the build status
[03:09:28.361] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:28.361] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:54:0) after 1m0s
[03:10:28.408] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:54:0)
[03:10:28.408] Timer tick, asking Buildbucket for the build status
[03:10:28.624] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:28.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:55:0) after 1m0s
[03:11:28.651] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:55:0)
[03:11:28.651] Timer tick, asking Buildbucket for the build status
[03:11:28.971] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:28.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:56:0) after 1m0s
[03:12:29.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:56:0)
[03:12:29.137] Timer tick, asking Buildbucket for the build status
[03:12:29.346] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:29.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:57:0) after 1m0s
[03:13:29.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:57:0)
[03:13:29.383] Timer tick, asking Buildbucket for the build status
[03:13:29.533] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:29.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:58:0) after 1m0s
[03:14:29.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:58:0)
[03:14:29.572] Timer tick, asking Buildbucket for the build status
[03:14:29.742] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:29.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:59:0) after 1m0s
[03:15:29.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:59:0)
[03:15:29.781] Timer tick, asking Buildbucket for the build status
[03:15:30.037] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:30.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:60:0) after 1m0s
[03:16:30.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:60:0)
[03:16:30.087] Timer tick, asking Buildbucket for the build status
[03:16:30.296] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:30.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:61:0) after 1m0s
[03:17:30.331] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:61:0)
[03:17:30.331] Timer tick, asking Buildbucket for the build status
[03:17:30.651] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:30.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:62:0) after 1m0s
[03:18:30.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:62:0)
[03:18:30.667] Timer tick, asking Buildbucket for the build status
[03:18:30.800] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:30.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:63:0) after 1m0s
[03:19:30.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:63:0)
[03:19:30.825] Timer tick, asking Buildbucket for the build status
[03:19:31.133] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:31.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:64:0) after 1m0s
[03:20:31.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:64:0)
[03:20:31.143] Timer tick, asking Buildbucket for the build status
[03:20:31.537] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:31.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:65:0) after 1m0s
[03:21:31.614] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:65:0)
[03:21:31.614] Timer tick, asking Buildbucket for the build status
[03:21:31.786] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:31.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:66:0) after 1m0s
[03:22:31.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:66:0)
[03:22:31.813] Timer tick, asking Buildbucket for the build status
[03:22:31.938] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:31.938] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:67:0) after 1m0s
[03:23:31.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:67:0)
[03:23:31.969] Timer tick, asking Buildbucket for the build status
[03:23:32.242] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:32.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:68:0) after 1m0s
[03:24:32.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:68:0)
[03:24:32.266] Timer tick, asking Buildbucket for the build status
[03:24:32.506] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:32.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:69:0) after 1m0s
[03:25:32.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:69:0)
[03:25:32.546] Timer tick, asking Buildbucket for the build status
[03:25:32.740] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:32.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:70:0) after 1m0s
[03:26:32.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:70:0)
[03:26:32.750] Timer tick, asking Buildbucket for the build status
[03:26:32.942] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:32.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:71:0) after 1m0s
[03:27:32.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:71:0)
[03:27:32.995] Timer tick, asking Buildbucket for the build status
[03:27:33.196] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:33.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:72:0) after 1m0s
[03:28:33.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:72:0)
[03:28:33.225] Timer tick, asking Buildbucket for the build status
[03:28:33.556] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:33.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:73:0) after 1m0s
[03:29:33.636] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:73:0)
[03:29:33.636] Timer tick, asking Buildbucket for the build status
[03:29:33.771] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:33.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:74:0) after 1m0s
[03:30:33.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:74:0)
[03:30:33.775] Timer tick, asking Buildbucket for the build status
[03:30:34.145] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:34.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:75:0) after 1m0s
[03:31:34.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:75:0)
[03:31:34.276] Timer tick, asking Buildbucket for the build status
[03:31:34.550] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:34.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:76:0) after 1m0s
[03:32:34.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:76:0)
[03:32:34.557] Timer tick, asking Buildbucket for the build status
[03:32:34.892] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:34.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:77:0) after 1m0s
[03:33:34.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:77:0)
[03:33:34.956] Timer tick, asking Buildbucket for the build status
[03:33:35.158] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:35.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:78:0) after 1m0s
[03:34:35.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:78:0)
[03:34:35.195] Timer tick, asking Buildbucket for the build status
[03:34:35.397] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:35.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:79:0) after 1m0s
[03:35:35.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:79:0)
[03:35:35.407] Timer tick, asking Buildbucket for the build status
[03:35:35.529] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:35.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:80:0) after 1m0s
[03:36:35.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:80:0)
[03:36:35.609] Timer tick, asking Buildbucket for the build status
[03:36:35.728] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:35.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:81:0) after 1m0s
[03:37:35.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:81:0)
[03:37:35.789] Timer tick, asking Buildbucket for the build status
[03:37:36.118] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:36.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:82:0) after 1m0s
[03:38:36.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:82:0)
[03:38:36.152] Timer tick, asking Buildbucket for the build status
[03:38:36.413] Build 8902468897974866416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:36.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067898960514944064:83:0) after 1m0s
[03:38:53.443] Received PubSub notification, asking Buildbucket for the build status
[03:38:53.605] Build 8902468897974866416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:38:53.605] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1568345932786108",
"created_by": "project:emscripten-releases",
"created_ts": "1568341081603529",
"id": "8902468897974866416",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"title\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"title\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-vweq\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"8fc70df4cab3589883092aef9ff6e43b9309cea2\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-vweq\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4436-e523514\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568342813371784",
"status": "COMPLETED",
"status_changed_ts": "1568345933107746",
"tags": [
"builder:win",
"buildset:commit/git/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/8fc70df4cab3589883092aef9ff6e43b9309cea2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067898960514944064",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8902468897974866416/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:47413b4b0bff4510",
"user_agent:luci-scheduler"
],
"updated_ts": "1568345933132941",
"url": "https://ci.chromium.org/b/8902468897974866416",
"utcnow_ts": "1568345933543548"
}
}
[03:38:53.605] Invocation finished in 1h20m53.808797995s with status SUCCEEDED