[09:18:26.348] New invocation is queued and will start shortly
[09:18:27.599] Starting the invocation (attempt 1)
[09:18:27.653] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:18:27.654] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:18:27.654] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9066694748013660944",
"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\":\"3e1b99f475f221a6675b50835a10dd5e4da576c6\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3e1b99f475f221a6675b50835a10dd5e4da576c6\",\"title\":\"3e1b99f475f221a6675b50835a10dd5e4da576c6\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9066694748013660944",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"gitiles_ref:refs/heads/master"
]
}
[09:18:28.263] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1569489507783740",
"id": "8901264685844760784",
"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\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"title\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1569489508114192",
"tags": [
"builder:win",
"buildset:commit/git/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066694748013660944",
"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/8901264685844760784/+/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": "1569489508114334",
"url": "https://ci.chromium.org/b/8901264685844760784",
"utcnow_ts": "1569489508254608"
}
}
[09:18:28.263] Task URL: https://ci.chromium.org/b/8901264685844760784
[09:18:28.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:2:0) after 1m0s
[09:19:07.127] Received PubSub notification, asking Buildbucket for the build status
[09:19:07.222] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:28.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:2:0)
[09:19:28.338] Timer tick, asking Buildbucket for the build status
[09:19:29.146] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:29.147] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:4:0) after 1m0s
[09:20:29.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:4:0)
[09:20:29.162] Timer tick, asking Buildbucket for the build status
[09:20:29.376] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:29.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:5:0) after 1m0s
[09:21:29.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:5:0)
[09:21:29.422] Timer tick, asking Buildbucket for the build status
[09:21:29.647] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:29.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:6:0) after 1m0s
[09:22:29.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:6:0)
[09:22:29.741] Timer tick, asking Buildbucket for the build status
[09:22:29.848] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:29.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:7:0) after 1m0s
[09:23:29.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:7:0)
[09:23:29.935] Timer tick, asking Buildbucket for the build status
[09:23:30.120] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:30.120] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:8:0) after 1m0s
[09:24:30.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:8:0)
[09:24:30.179] Timer tick, asking Buildbucket for the build status
[09:24:30.849] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:30.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:9:0) after 1m0s
[09:25:30.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:9:0)
[09:25:30.980] Timer tick, asking Buildbucket for the build status
[09:25:31.137] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:31.137] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:10:0) after 1m0s
[09:26:31.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:10:0)
[09:26:31.152] Timer tick, asking Buildbucket for the build status
[09:26:31.329] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:31.330] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:11:0) after 1m0s
[09:27:31.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:11:0)
[09:27:31.335] Timer tick, asking Buildbucket for the build status
[09:27:32.374] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:32.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:12:0) after 1m0s
[09:28:32.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:12:0)
[09:28:32.514] Timer tick, asking Buildbucket for the build status
[09:28:33.034] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:33.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:13:0) after 1m0s
[09:29:33.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:13:0)
[09:29:33.102] Timer tick, asking Buildbucket for the build status
[09:29:34.134] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:34.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:14:0) after 1m0s
[09:30:34.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:14:0)
[09:30:34.195] Timer tick, asking Buildbucket for the build status
[09:30:34.404] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:34.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:15:0) after 1m0s
[09:31:34.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:15:0)
[09:31:34.510] Timer tick, asking Buildbucket for the build status
[09:31:34.631] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:34.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:16:0) after 1m0s
[09:32:34.621] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:16:0)
[09:32:34.621] Timer tick, asking Buildbucket for the build status
[09:32:34.948] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:34.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:17:0) after 1m0s
[09:33:35.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:17:0)
[09:33:35.009] Timer tick, asking Buildbucket for the build status
[09:33:35.373] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:35.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:18:0) after 1m0s
[09:34:35.402] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:18:0)
[09:34:35.402] Timer tick, asking Buildbucket for the build status
[09:34:35.751] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:35.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:19:0) after 1m0s
[09:35:35.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:19:0)
[09:35:35.832] Timer tick, asking Buildbucket for the build status
[09:35:37.499] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:37.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:20:0) after 1m0s
[09:36:37.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:20:0)
[09:36:37.542] Timer tick, asking Buildbucket for the build status
[09:36:37.951] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:37.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:21:0) after 1m0s
[09:37:37.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:21:0)
[09:37:37.983] Timer tick, asking Buildbucket for the build status
[09:37:38.334] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:38.334] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:22:0) after 1m0s
[09:38:38.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:22:0)
[09:38:38.443] Timer tick, asking Buildbucket for the build status
[09:38:38.528] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:38.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:23:0) after 1m0s
[09:39:38.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:23:0)
[09:39:38.586] Timer tick, asking Buildbucket for the build status
[09:39:38.991] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:38.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:24:0) after 1m0s
[09:40:39.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:24:0)
[09:40:39.025] Timer tick, asking Buildbucket for the build status
[09:40:41.547] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:41.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:25:0) after 1m0s
[09:41:41.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:25:0)
[09:41:41.609] Timer tick, asking Buildbucket for the build status
[09:41:41.863] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:41.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:26:0) after 1m0s
[09:42:41.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:26:0)
[09:42:41.926] Timer tick, asking Buildbucket for the build status
[09:42:42.335] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:42.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:27:0) after 1m0s
[09:43:42.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:27:0)
[09:43:42.435] Timer tick, asking Buildbucket for the build status
[09:43:42.929] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:42.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:28:0) after 1m0s
[09:44:42.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:28:0)
[09:44:42.954] Timer tick, asking Buildbucket for the build status
[09:44:43.167] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:43.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:29:0) after 1m0s
[09:45:43.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:29:0)
[09:45:43.302] Timer tick, asking Buildbucket for the build status
[09:45:43.644] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:43.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:30:0) after 1m0s
[09:46:43.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:30:0)
[09:46:43.759] Timer tick, asking Buildbucket for the build status
[09:46:43.899] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:43.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:31:0) after 1m0s
[09:47:43.964] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:31:0)
[09:47:43.964] Timer tick, asking Buildbucket for the build status
[09:47:44.275] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:44.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:32:0) after 1m0s
[09:48:44.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:32:0)
[09:48:44.369] Timer tick, asking Buildbucket for the build status
[09:48:44.512] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:44.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:33:0) after 1m0s
[09:49:44.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:33:0)
[09:49:44.970] Timer tick, asking Buildbucket for the build status
[09:49:45.559] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:45.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:34:0) after 1m0s
[09:50:45.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:34:0)
[09:50:45.697] Timer tick, asking Buildbucket for the build status
[09:50:45.992] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:45.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:35:0) after 1m0s
[09:51:46.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:35:0)
[09:51:46.134] Timer tick, asking Buildbucket for the build status
[09:51:46.251] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:46.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:36:0) after 1m0s
[09:52:46.410] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:36:0)
[09:52:46.410] Timer tick, asking Buildbucket for the build status
[09:52:47.425] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:47.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:37:0) after 1m0s
[09:53:47.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:37:0)
[09:53:47.540] Timer tick, asking Buildbucket for the build status
[09:53:48.435] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:38:0) after 1m0s
[09:54:48.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:38:0)
[09:54:48.555] Timer tick, asking Buildbucket for the build status
[09:54:48.700] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:48.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:39:0) after 1m0s
[09:55:48.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:39:0)
[09:55:48.832] Timer tick, asking Buildbucket for the build status
[09:55:49.288] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:49.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:40:0) after 1m0s
[09:56:49.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:40:0)
[09:56:49.393] Timer tick, asking Buildbucket for the build status
[09:56:49.501] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:49.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:41:0) after 1m0s
[09:57:49.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:41:0)
[09:57:49.570] Timer tick, asking Buildbucket for the build status
[09:57:49.864] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:49.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:42:0) after 1m0s
[09:58:50.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:42:0)
[09:58:50.011] Timer tick, asking Buildbucket for the build status
[09:58:51.483] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:51.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:43:0) after 1m0s
[09:59:51.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:43:0)
[09:59:51.528] Timer tick, asking Buildbucket for the build status
[09:59:51.751] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:51.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:44:0) after 1m0s
[10:00:51.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:44:0)
[10:00:51.843] Timer tick, asking Buildbucket for the build status
[10:00:52.788] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:52.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:45:0) after 1m0s
[10:01:52.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:45:0)
[10:01:52.812] Timer tick, asking Buildbucket for the build status
[10:01:52.940] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:52.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:46:0) after 1m0s
[10:02:52.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:46:0)
[10:02:52.973] Timer tick, asking Buildbucket for the build status
[10:02:53.220] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:53.220] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:47:0) after 1m0s
[10:03:53.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:47:0)
[10:03:53.251] Timer tick, asking Buildbucket for the build status
[10:03:53.414] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:53.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:48:0) after 1m0s
[10:04:53.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:48:0)
[10:04:53.427] Timer tick, asking Buildbucket for the build status
[10:04:53.535] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:53.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:49:0) after 1m0s
[10:05:53.684] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:49:0)
[10:05:53.684] Timer tick, asking Buildbucket for the build status
[10:05:54.228] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:54.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:50:0) after 1m0s
[10:06:54.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:50:0)
[10:06:54.394] Timer tick, asking Buildbucket for the build status
[10:06:54.577] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:54.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:51:0) after 1m0s
[10:07:54.651] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:51:0)
[10:07:54.651] Timer tick, asking Buildbucket for the build status
[10:07:54.716] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:54.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:52:0) after 1m0s
[10:08:54.790] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:52:0)
[10:08:54.790] Timer tick, asking Buildbucket for the build status
[10:08:55.152] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:55.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:53:0) after 1m0s
[10:09:55.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:53:0)
[10:09:55.184] Timer tick, asking Buildbucket for the build status
[10:09:55.323] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:55.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:54:0) after 1m0s
[10:10:55.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:54:0)
[10:10:55.399] Timer tick, asking Buildbucket for the build status
[10:10:55.466] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:55.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:55:0) after 1m0s
[10:11:55.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:55:0)
[10:11:55.538] Timer tick, asking Buildbucket for the build status
[10:11:55.721] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:55.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:56:0) after 1m0s
[10:12:55.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:56:0)
[10:12:55.806] Timer tick, asking Buildbucket for the build status
[10:12:56.704] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:56.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:57:0) after 1m0s
[10:13:56.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:57:0)
[10:13:56.742] Timer tick, asking Buildbucket for the build status
[10:13:56.916] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:56.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:58:0) after 1m0s
[10:14:56.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:58:0)
[10:14:56.985] Timer tick, asking Buildbucket for the build status
[10:14:57.111] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:57.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:59:0) after 1m0s
[10:15:57.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:59:0)
[10:15:57.154] Timer tick, asking Buildbucket for the build status
[10:15:58.264] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:58.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:60:0) after 1m0s
[10:16:58.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:60:0)
[10:16:58.379] Timer tick, asking Buildbucket for the build status
[10:16:58.488] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:58.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:61:0) after 1m0s
[10:17:58.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:61:0)
[10:17:58.526] Timer tick, asking Buildbucket for the build status
[10:17:58.786] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:58.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:62:0) after 1m0s
[10:18:58.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:62:0)
[10:18:58.922] Timer tick, asking Buildbucket for the build status
[10:19:00.339] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:00.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:63:0) after 1m0s
[10:20:00.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:63:0)
[10:20:00.366] Timer tick, asking Buildbucket for the build status
[10:20:02.138] Build 8901264685844760784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:02.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9066694748013660944:64:0) after 1m0s
[10:20:20.491] Received PubSub notification, asking Buildbucket for the build status
[10:20:20.641] Build 8901264685844760784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:20:20.641] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1569493219594520",
"created_by": "project:emscripten-releases",
"created_ts": "1569489507783740",
"id": "8901264685844760784",
"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\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"title\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}}",
"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\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"title\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-xe9y\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3e1b99f475f221a6675b50835a10dd5e4da576c6\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-xe9y\"], \"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\": [\"4443-3a52fcb\"], \"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": "1569489546406664",
"status": "COMPLETED",
"status_changed_ts": "1569493219884411",
"tags": [
"builder:win",
"buildset:commit/git/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3e1b99f475f221a6675b50835a10dd5e4da576c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066694748013660944",
"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/8901264685844760784/+/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:4785af3e916ba610",
"user_agent:luci-scheduler"
],
"updated_ts": "1569493219916350",
"url": "https://ci.chromium.org/b/8901264685844760784",
"utcnow_ts": "1569493220620784"
}
}
[10:20:20.641] Invocation finished in 1h1m54.309231717s with status SUCCEEDED