[13:29:10.298] New invocation is queued and will start shortly
[13:29:11.371] Starting the invocation (attempt 1)
[13:29:11.404] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:29:11.404] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:29:11.404] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9026906905037866960",
"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\":\"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\",\"title\":\"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9026906905037866960",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"gitiles_ref:refs/heads/master"
]
}
[13:29:11.902] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1607434151501798",
"id": "8861476843114232784",
"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\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"title\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607434151769232",
"tags": [
"builder:win",
"buildset:commit/git/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026906905037866960",
"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/8861476843114232784/+/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": "1607434151769460",
"url": "https://ci.chromium.org/b/8861476843114232784",
"utcnow_ts": "1607434151893258"
}
}
[13:29:11.902] Task URL: https://ci.chromium.org/b/8861476843114232784
[13:29:11.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:2:0) after 1m0s
[13:29:45.316] Received PubSub notification, asking Buildbucket for the build status
[13:29:45.383] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:11.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:2:0)
[13:30:11.940] Timer tick, asking Buildbucket for the build status
[13:30:12.020] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:12.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:4:0) after 1m0s
[13:31:12.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:4:0)
[13:31:12.038] Timer tick, asking Buildbucket for the build status
[13:31:12.130] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:12.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:5:0) after 1m0s
[13:32:12.263] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:5:0)
[13:32:12.263] Timer tick, asking Buildbucket for the build status
[13:32:12.394] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:12.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:6:0) after 1m0s
[13:33:12.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:6:0)
[13:33:12.426] Timer tick, asking Buildbucket for the build status
[13:33:12.575] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:12.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:7:0) after 1m0s
[13:34:12.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:7:0)
[13:34:12.824] Timer tick, asking Buildbucket for the build status
[13:34:13.015] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:13.015] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:8:0) after 1m0s
[13:35:13.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:8:0)
[13:35:13.033] Timer tick, asking Buildbucket for the build status
[13:35:13.343] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:13.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:9:0) after 1m0s
[13:36:13.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:9:0)
[13:36:13.976] Timer tick, asking Buildbucket for the build status
[13:36:14.039] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:14.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:10:0) after 1m0s
[13:37:14.127] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:10:0)
[13:37:14.127] Timer tick, asking Buildbucket for the build status
[13:37:14.429] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:14.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:11:0) after 1m0s
[13:38:14.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:11:0)
[13:38:14.478] Timer tick, asking Buildbucket for the build status
[13:38:14.710] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:14.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:12:0) after 1m0s
[13:39:14.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:12:0)
[13:39:14.975] Timer tick, asking Buildbucket for the build status
[13:39:15.135] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:15.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:13:0) after 1m0s
[13:40:15.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:13:0)
[13:40:15.239] Timer tick, asking Buildbucket for the build status
[13:40:15.344] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:15.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:14:0) after 1m0s
[13:41:15.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:14:0)
[13:41:15.466] Timer tick, asking Buildbucket for the build status
[13:41:15.591] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:15.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:15:0) after 1m0s
[13:42:15.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:15:0)
[13:42:15.675] Timer tick, asking Buildbucket for the build status
[13:42:15.739] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:16:0) after 1m0s
[13:43:15.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:16:0)
[13:43:15.751] Timer tick, asking Buildbucket for the build status
[13:43:15.919] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:15.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:17:0) after 1m0s
[13:44:15.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:17:0)
[13:44:15.951] Timer tick, asking Buildbucket for the build status
[13:44:16.222] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:16.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:18:0) after 1m0s
[13:45:16.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:18:0)
[13:45:16.241] Timer tick, asking Buildbucket for the build status
[13:45:16.498] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:16.498] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:19:0) after 1m0s
[13:46:16.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:19:0)
[13:46:16.648] Timer tick, asking Buildbucket for the build status
[13:46:16.713] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:16.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:20:0) after 1m0s
[13:47:16.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:20:0)
[13:47:16.744] Timer tick, asking Buildbucket for the build status
[13:47:16.813] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:16.813] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:21:0) after 1m0s
[13:48:16.893] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:21:0)
[13:48:16.893] Timer tick, asking Buildbucket for the build status
[13:48:16.975] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:16.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:22:0) after 1m0s
[13:49:17.084] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:22:0)
[13:49:17.084] Timer tick, asking Buildbucket for the build status
[13:49:17.402] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:17.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:23:0) after 1m0s
[13:50:17.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:23:0)
[13:50:17.518] Timer tick, asking Buildbucket for the build status
[13:50:17.593] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:17.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:24:0) after 1m0s
[13:51:17.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:24:0)
[13:51:17.646] Timer tick, asking Buildbucket for the build status
[13:51:17.713] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:17.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:25:0) after 1m0s
[13:52:17.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:25:0)
[13:52:17.767] Timer tick, asking Buildbucket for the build status
[13:52:17.851] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:17.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:26:0) after 1m0s
[13:53:18.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:26:0)
[13:53:18.414] Timer tick, asking Buildbucket for the build status
[13:53:18.472] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:18.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:27:0) after 1m0s
[13:54:18.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:27:0)
[13:54:18.564] Timer tick, asking Buildbucket for the build status
[13:54:18.689] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:18.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:28:0) after 1m0s
[13:55:18.801] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:28:0)
[13:55:18.801] Timer tick, asking Buildbucket for the build status
[13:55:19.393] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:19.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:29:0) after 1m0s
[13:56:19.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:29:0)
[13:56:19.413] Timer tick, asking Buildbucket for the build status
[13:56:19.481] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:19.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:30:0) after 1m0s
[13:57:19.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:30:0)
[13:57:19.521] Timer tick, asking Buildbucket for the build status
[13:57:19.625] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:19.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:31:0) after 1m0s
[13:58:19.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:31:0)
[13:58:19.702] Timer tick, asking Buildbucket for the build status
[13:58:19.756] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:19.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:32:0) after 1m0s
[13:59:19.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:32:0)
[13:59:19.805] Timer tick, asking Buildbucket for the build status
[13:59:20.295] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:20.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:33:0) after 1m0s
[14:00:20.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:33:0)
[14:00:20.340] Timer tick, asking Buildbucket for the build status
[14:00:20.425] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:20.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:34:0) after 1m0s
[14:01:20.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:34:0)
[14:01:20.447] Timer tick, asking Buildbucket for the build status
[14:01:20.712] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:20.712] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:35:0) after 1m0s
[14:02:20.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:35:0)
[14:02:20.772] Timer tick, asking Buildbucket for the build status
[14:02:20.907] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:20.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:36:0) after 1m0s
[14:03:20.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:36:0)
[14:03:20.962] Timer tick, asking Buildbucket for the build status
[14:03:21.033] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:21.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:37:0) after 1m0s
[14:04:21.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:37:0)
[14:04:21.085] Timer tick, asking Buildbucket for the build status
[14:04:21.261] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:21.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:38:0) after 1m0s
[14:05:21.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:38:0)
[14:05:21.317] Timer tick, asking Buildbucket for the build status
[14:05:21.387] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:21.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:39:0) after 1m0s
[14:06:21.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:39:0)
[14:06:21.533] Timer tick, asking Buildbucket for the build status
[14:06:21.902] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:21.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:40:0) after 1m0s
[14:07:22.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:40:0)
[14:07:22.002] Timer tick, asking Buildbucket for the build status
[14:07:22.105] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:22.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:41:0) after 1m0s
[14:08:22.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:41:0)
[14:08:22.168] Timer tick, asking Buildbucket for the build status
[14:08:22.233] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:22.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:42:0) after 1m0s
[14:09:22.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:42:0)
[14:09:22.221] Timer tick, asking Buildbucket for the build status
[14:09:22.336] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:22.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:43:0) after 1m0s
[14:10:22.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:43:0)
[14:10:22.524] Timer tick, asking Buildbucket for the build status
[14:10:22.784] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:22.784] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:44:0) after 1m0s
[14:11:22.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:44:0)
[14:11:22.899] Timer tick, asking Buildbucket for the build status
[14:11:22.961] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:22.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:45:0) after 1m0s
[14:12:23.048] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:45:0)
[14:12:23.048] Timer tick, asking Buildbucket for the build status
[14:12:23.112] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:23.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:46:0) after 1m0s
[14:13:23.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:46:0)
[14:13:23.213] Timer tick, asking Buildbucket for the build status
[14:13:23.280] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:23.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:47:0) after 1m0s
[14:14:23.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:47:0)
[14:14:23.301] Timer tick, asking Buildbucket for the build status
[14:14:23.470] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:23.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:48:0) after 1m0s
[14:15:23.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:48:0)
[14:15:23.498] Timer tick, asking Buildbucket for the build status
[14:15:23.563] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:23.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:49:0) after 1m0s
[14:16:23.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:49:0)
[14:16:23.658] Timer tick, asking Buildbucket for the build status
[14:16:23.727] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:23.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:50:0) after 1m0s
[14:17:23.801] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:50:0)
[14:17:23.801] Timer tick, asking Buildbucket for the build status
[14:17:23.866] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:23.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:51:0) after 1m0s
[14:18:24.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:51:0)
[14:18:24.028] Timer tick, asking Buildbucket for the build status
[14:18:24.467] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:24.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:52:0) after 1m0s
[14:19:24.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:52:0)
[14:19:24.519] Timer tick, asking Buildbucket for the build status
[14:19:24.635] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:24.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:53:0) after 1m0s
[14:20:24.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:53:0)
[14:20:24.662] Timer tick, asking Buildbucket for the build status
[14:20:25.320] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:25.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:54:0) after 1m0s
[14:21:25.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:54:0)
[14:21:25.301] Timer tick, asking Buildbucket for the build status
[14:21:25.370] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:25.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:55:0) after 1m0s
[14:22:25.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:55:0)
[14:22:25.393] Timer tick, asking Buildbucket for the build status
[14:22:25.561] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:25.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:56:0) after 1m0s
[14:23:25.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:56:0)
[14:23:25.548] Timer tick, asking Buildbucket for the build status
[14:23:25.627] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:25.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:57:0) after 1m0s
[14:24:25.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:57:0)
[14:24:25.639] Timer tick, asking Buildbucket for the build status
[14:24:25.895] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:25.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:58:0) after 1m0s
[14:25:25.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:58:0)
[14:25:25.932] Timer tick, asking Buildbucket for the build status
[14:25:26.008] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:26.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:59:0) after 1m0s
[14:26:26.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:59:0)
[14:26:26.184] Timer tick, asking Buildbucket for the build status
[14:26:26.247] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:26.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:60:0) after 1m0s
[14:27:26.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:60:0)
[14:27:26.507] Timer tick, asking Buildbucket for the build status
[14:27:26.583] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:26.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:61:0) after 1m0s
[14:28:26.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:61:0)
[14:28:26.870] Timer tick, asking Buildbucket for the build status
[14:28:27.552] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:27.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:62:0) after 1m0s
[14:29:27.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:62:0)
[14:29:27.604] Timer tick, asking Buildbucket for the build status
[14:29:27.736] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:27.736] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:63:0) after 1m0s
[14:30:27.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:63:0)
[14:30:27.831] Timer tick, asking Buildbucket for the build status
[14:30:27.925] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:27.925] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:64:0) after 1m0s
[14:31:28.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:64:0)
[14:31:28.058] Timer tick, asking Buildbucket for the build status
[14:31:28.204] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:28.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:65:0) after 1m0s
[14:32:28.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:65:0)
[14:32:28.262] Timer tick, asking Buildbucket for the build status
[14:32:28.624] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:28.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:66:0) after 1m0s
[14:33:28.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:66:0)
[14:33:28.732] Timer tick, asking Buildbucket for the build status
[14:33:28.793] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:28.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:67:0) after 1m0s
[14:34:28.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:67:0)
[14:34:28.867] Timer tick, asking Buildbucket for the build status
[14:34:29.067] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:29.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:68:0) after 1m0s
[14:35:29.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:68:0)
[14:35:29.076] Timer tick, asking Buildbucket for the build status
[14:35:29.151] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:29.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:69:0) after 1m0s
[14:36:29.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:69:0)
[14:36:29.221] Timer tick, asking Buildbucket for the build status
[14:36:29.571] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:29.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:70:0) after 1m0s
[14:37:29.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:70:0)
[14:37:29.683] Timer tick, asking Buildbucket for the build status
[14:37:29.743] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:29.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:71:0) after 1m0s
[14:38:29.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:71:0)
[14:38:29.739] Timer tick, asking Buildbucket for the build status
[14:38:29.803] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:29.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:72:0) after 1m0s
[14:39:29.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:72:0)
[14:39:29.815] Timer tick, asking Buildbucket for the build status
[14:39:31.446] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:31.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:73:0) after 1m0s
[14:40:31.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:73:0)
[14:40:31.499] Timer tick, asking Buildbucket for the build status
[14:40:31.560] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:31.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:74:0) after 1m0s
[14:41:31.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:74:0)
[14:41:31.589] Timer tick, asking Buildbucket for the build status
[14:41:31.661] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:31.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:75:0) after 1m0s
[14:42:31.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:75:0)
[14:42:31.696] Timer tick, asking Buildbucket for the build status
[14:42:31.953] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:31.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:76:0) after 1m0s
[14:43:31.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:76:0)
[14:43:31.974] Timer tick, asking Buildbucket for the build status
[14:43:32.043] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:32.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:77:0) after 1m0s
[14:44:32.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:77:0)
[14:44:32.064] Timer tick, asking Buildbucket for the build status
[14:44:32.171] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:32.171] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:78:0) after 1m0s
[14:45:32.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:78:0)
[14:45:32.196] Timer tick, asking Buildbucket for the build status
[14:45:32.394] Build 8861476843114232784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:32.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9026906905037866960:79:0) after 1m0s
[14:46:10.162] Received PubSub notification, asking Buildbucket for the build status
[14:46:10.223] Build 8861476843114232784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:46:10.223] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607438769419641",
"created_by": "project:emscripten-releases",
"created_ts": "1607434151501798",
"id": "8861476843114232784",
"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\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"title\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$recipe_engine/buildbucket.build.infra.swarming.priority\"], \"$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\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"title\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-thjz\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"71e5aeb0178ef08a921d18100cb9cec51c7cbd6c\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-thjz\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607434184383378",
"status": "COMPLETED",
"status_changed_ts": "1607438769726491",
"tags": [
"builder:win",
"buildset:commit/git/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/71e5aeb0178ef08a921d18100cb9cec51c7cbd6c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026906905037866960",
"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/8861476843114232784/+/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:505b5c3e584fa310",
"user_agent:luci-scheduler"
],
"updated_ts": "1607438769796896",
"url": "https://ci.chromium.org/b/8861476843114232784",
"utcnow_ts": "1607438770216933"
}
}
[14:46:10.223] Invocation finished in 1h16m59.939823862s with status SUCCEEDED