[19:40:24.979] New invocation is queued and will start shortly
[19:40:26.128] Starting the invocation (attempt 1)
[19:40:26.246] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:40:26.249] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:40:26.249] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9032500560263022976",
"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\":\"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@72abc72d3c27961c2055010ab5dd5b24edc1ee65\",\"title\":\"72abc72d3c27961c2055010ab5dd5b24edc1ee65\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9032500560263022976",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"gitiles_ref:refs/heads/master"
]
}
[19:40:26.818] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1602099626338915",
"id": "8867070498170983264",
"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\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"title\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602099626621206",
"tags": [
"builder:win",
"buildset:commit/git/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032500560263022976",
"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/8867070498170983264/+/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": "1602099626621338",
"url": "https://ci.chromium.org/b/8867070498170983264",
"utcnow_ts": "1602099626811178"
}
}
[19:40:26.818] Task URL: https://ci.chromium.org/b/8867070498170983264
[19:40:26.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:2:0) after 1m0s
[19:41:26.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:2:0)
[19:41:26.921] Timer tick, asking Buildbucket for the build status
[19:41:27.018] Build 8867070498170983264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:27.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:3:0) after 1m0s
[19:41:27.705] Received PubSub notification, asking Buildbucket for the build status
[19:41:27.796] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:27.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:3:0)
[19:42:27.054] Timer tick, asking Buildbucket for the build status
[19:42:27.125] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:27.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:5:0) after 1m0s
[19:43:27.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:5:0)
[19:43:27.182] Timer tick, asking Buildbucket for the build status
[19:43:27.359] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:6:0) after 1m0s
[19:44:27.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:6:0)
[19:44:27.404] Timer tick, asking Buildbucket for the build status
[19:44:27.693] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:27.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:7:0) after 1m0s
[19:45:27.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:7:0)
[19:45:27.717] Timer tick, asking Buildbucket for the build status
[19:45:27.787] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:27.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:8:0) after 1m0s
[19:46:27.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:8:0)
[19:46:27.855] Timer tick, asking Buildbucket for the build status
[19:46:28.030] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:28.030] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:9:0) after 1m0s
[19:47:28.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:9:0)
[19:47:28.129] Timer tick, asking Buildbucket for the build status
[19:47:28.250] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:28.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:10:0) after 1m0s
[19:48:28.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:10:0)
[19:48:28.367] Timer tick, asking Buildbucket for the build status
[19:48:28.663] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:28.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:11:0) after 1m0s
[19:49:28.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:11:0)
[19:49:28.707] Timer tick, asking Buildbucket for the build status
[19:49:28.821] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:28.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:12:0) after 1m0s
[19:50:28.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:12:0)
[19:50:28.799] Timer tick, asking Buildbucket for the build status
[19:50:28.917] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:28.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:13:0) after 1m0s
[19:51:28.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:13:0)
[19:51:28.962] Timer tick, asking Buildbucket for the build status
[19:51:29.082] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:29.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:14:0) after 1m0s
[19:52:29.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:14:0)
[19:52:29.149] Timer tick, asking Buildbucket for the build status
[19:52:29.211] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:29.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:15:0) after 1m0s
[19:53:29.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:15:0)
[19:53:29.275] Timer tick, asking Buildbucket for the build status
[19:53:29.929] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:29.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:16:0) after 1m0s
[19:54:29.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:16:0)
[19:54:29.984] Timer tick, asking Buildbucket for the build status
[19:54:30.187] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:30.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:17:0) after 1m0s
[19:55:30.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:17:0)
[19:55:30.160] Timer tick, asking Buildbucket for the build status
[19:55:30.348] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:30.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:18:0) after 1m0s
[19:56:30.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:18:0)
[19:56:30.370] Timer tick, asking Buildbucket for the build status
[19:56:30.429] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:30.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:19:0) after 1m0s
[19:57:30.455] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:19:0)
[19:57:30.455] Timer tick, asking Buildbucket for the build status
[19:57:30.525] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:30.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:20:0) after 1m0s
[19:58:30.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:20:0)
[19:58:30.550] Timer tick, asking Buildbucket for the build status
[19:58:30.617] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:30.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:21:0) after 1m0s
[19:59:30.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:21:0)
[19:59:30.686] Timer tick, asking Buildbucket for the build status
[19:59:30.738] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:30.738] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:22:0) after 1m0s
[20:00:30.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:22:0)
[20:00:30.768] Timer tick, asking Buildbucket for the build status
[20:00:30.908] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:30.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:23:0) after 1m0s
[20:01:30.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:23:0)
[20:01:30.991] Timer tick, asking Buildbucket for the build status
[20:01:31.062] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:31.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:24:0) after 1m0s
[20:02:31.130] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:24:0)
[20:02:31.130] Timer tick, asking Buildbucket for the build status
[20:02:31.197] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:31.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:25:0) after 1m0s
[20:03:31.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:25:0)
[20:03:31.337] Timer tick, asking Buildbucket for the build status
[20:03:31.487] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:31.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:26:0) after 1m0s
[20:04:31.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:26:0)
[20:04:31.555] Timer tick, asking Buildbucket for the build status
[20:04:31.669] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:31.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:27:0) after 1m0s
[20:05:31.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:27:0)
[20:05:31.921] Timer tick, asking Buildbucket for the build status
[20:05:32.035] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:32.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:28:0) after 1m0s
[20:06:32.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:28:0)
[20:06:32.181] Timer tick, asking Buildbucket for the build status
[20:06:32.249] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:32.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:29:0) after 1m0s
[20:07:32.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:29:0)
[20:07:32.318] Timer tick, asking Buildbucket for the build status
[20:07:32.386] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:32.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:30:0) after 1m0s
[20:08:32.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:30:0)
[20:08:32.417] Timer tick, asking Buildbucket for the build status
[20:08:32.504] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:32.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:31:0) after 1m0s
[20:09:32.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:31:0)
[20:09:32.656] Timer tick, asking Buildbucket for the build status
[20:09:32.716] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:32.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:32:0) after 1m0s
[20:10:32.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:32:0)
[20:10:32.872] Timer tick, asking Buildbucket for the build status
[20:10:33.003] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:33.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:33:0) after 1m0s
[20:11:33.060] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:33:0)
[20:11:33.060] Timer tick, asking Buildbucket for the build status
[20:11:33.587] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:33.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:34:0) after 1m0s
[20:12:33.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:34:0)
[20:12:33.695] Timer tick, asking Buildbucket for the build status
[20:12:33.791] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:33.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:35:0) after 1m0s
[20:13:33.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:35:0)
[20:13:33.862] Timer tick, asking Buildbucket for the build status
[20:13:33.918] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:33.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:36:0) after 1m0s
[20:14:33.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:36:0)
[20:14:33.925] Timer tick, asking Buildbucket for the build status
[20:14:33.987] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:33.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:37:0) after 1m0s
[20:15:34.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:37:0)
[20:15:34.013] Timer tick, asking Buildbucket for the build status
[20:15:34.111] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:34.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:38:0) after 1m0s
[20:16:34.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:38:0)
[20:16:34.102] Timer tick, asking Buildbucket for the build status
[20:16:34.174] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:34.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:39:0) after 1m0s
[20:17:34.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:39:0)
[20:17:34.249] Timer tick, asking Buildbucket for the build status
[20:17:34.324] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:34.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:40:0) after 1m0s
[20:18:34.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:40:0)
[20:18:34.559] Timer tick, asking Buildbucket for the build status
[20:18:34.613] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:34.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:41:0) after 1m0s
[20:19:34.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:41:0)
[20:19:34.700] Timer tick, asking Buildbucket for the build status
[20:19:34.757] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:34.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:42:0) after 1m0s
[20:20:34.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:42:0)
[20:20:34.836] Timer tick, asking Buildbucket for the build status
[20:20:35.167] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:35.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:43:0) after 1m0s
[20:21:35.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:43:0)
[20:21:35.191] Timer tick, asking Buildbucket for the build status
[20:21:35.271] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:35.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:44:0) after 1m0s
[20:22:35.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:44:0)
[20:22:35.333] Timer tick, asking Buildbucket for the build status
[20:22:35.385] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:35.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:45:0) after 1m0s
[20:23:35.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:45:0)
[20:23:35.466] Timer tick, asking Buildbucket for the build status
[20:23:35.535] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:35.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:46:0) after 1m0s
[20:24:35.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:46:0)
[20:24:35.589] Timer tick, asking Buildbucket for the build status
[20:24:35.675] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:35.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:47:0) after 1m0s
[20:25:35.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:47:0)
[20:25:35.698] Timer tick, asking Buildbucket for the build status
[20:25:35.782] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:35.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:48:0) after 1m0s
[20:26:35.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:48:0)
[20:26:35.758] Timer tick, asking Buildbucket for the build status
[20:26:35.825] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:35.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:49:0) after 1m0s
[20:27:35.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:49:0)
[20:27:35.836] Timer tick, asking Buildbucket for the build status
[20:27:35.896] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:35.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:50:0) after 1m0s
[20:28:35.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:50:0)
[20:28:35.938] Timer tick, asking Buildbucket for the build status
[20:28:36.014] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:36.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:51:0) after 1m0s
[20:29:36.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:51:0)
[20:29:36.083] Timer tick, asking Buildbucket for the build status
[20:29:36.329] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:36.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:52:0) after 1m0s
[20:30:36.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:52:0)
[20:30:36.354] Timer tick, asking Buildbucket for the build status
[20:30:36.703] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:36.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:53:0) after 1m0s
[20:31:36.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:53:0)
[20:31:36.804] Timer tick, asking Buildbucket for the build status
[20:31:36.984] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:36.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:54:0) after 1m0s
[20:32:37.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:54:0)
[20:32:37.108] Timer tick, asking Buildbucket for the build status
[20:32:37.221] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:37.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:55:0) after 1m0s
[20:33:37.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:55:0)
[20:33:37.297] Timer tick, asking Buildbucket for the build status
[20:33:37.364] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:37.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:56:0) after 1m0s
[20:34:37.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:56:0)
[20:34:37.458] Timer tick, asking Buildbucket for the build status
[20:34:37.706] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:37.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:57:0) after 1m0s
[20:35:37.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:57:0)
[20:35:37.729] Timer tick, asking Buildbucket for the build status
[20:35:37.816] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:37.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:58:0) after 1m0s
[20:36:37.903] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:58:0)
[20:36:37.903] Timer tick, asking Buildbucket for the build status
[20:36:38.047] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:38.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:59:0) after 1m0s
[20:37:38.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:59:0)
[20:37:38.181] Timer tick, asking Buildbucket for the build status
[20:37:38.243] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:38.244] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:60:0) after 1m0s
[20:38:39.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:60:0)
[20:38:39.029] Timer tick, asking Buildbucket for the build status
[20:38:39.325] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:39.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:61:0) after 1m0s
[20:39:39.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:61:0)
[20:39:39.532] Timer tick, asking Buildbucket for the build status
[20:39:39.598] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:39.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:62:0) after 1m0s
[20:40:39.721] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:62:0)
[20:40:39.721] Timer tick, asking Buildbucket for the build status
[20:40:40.132] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:40.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:63:0) after 1m0s
[20:41:40.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:63:0)
[20:41:40.250] Timer tick, asking Buildbucket for the build status
[20:41:40.336] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:40.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:64:0) after 1m0s
[20:42:40.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:64:0)
[20:42:40.453] Timer tick, asking Buildbucket for the build status
[20:42:40.552] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:40.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:65:0) after 1m0s
[20:43:40.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:65:0)
[20:43:40.628] Timer tick, asking Buildbucket for the build status
[20:43:40.716] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:40.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:66:0) after 1m0s
[20:44:40.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:66:0)
[20:44:40.802] Timer tick, asking Buildbucket for the build status
[20:44:40.872] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:40.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:67:0) after 1m0s
[20:45:40.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:67:0)
[20:45:40.930] Timer tick, asking Buildbucket for the build status
[20:45:41.034] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:41.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:68:0) after 1m0s
[20:46:41.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:68:0)
[20:46:41.096] Timer tick, asking Buildbucket for the build status
[20:46:41.186] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:41.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:69:0) after 1m0s
[20:47:41.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:69:0)
[20:47:41.298] Timer tick, asking Buildbucket for the build status
[20:47:41.364] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:41.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:70:0) after 1m0s
[20:48:41.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:70:0)
[20:48:41.573] Timer tick, asking Buildbucket for the build status
[20:48:41.645] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:41.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:71:0) after 1m0s
[20:49:41.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:71:0)
[20:49:41.755] Timer tick, asking Buildbucket for the build status
[20:49:41.920] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:41.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:72:0) after 1m0s
[20:50:42.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:72:0)
[20:50:42.066] Timer tick, asking Buildbucket for the build status
[20:50:42.157] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:42.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:73:0) after 1m0s
[20:51:42.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:73:0)
[20:51:42.313] Timer tick, asking Buildbucket for the build status
[20:51:43.230] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:43.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:74:0) after 1m0s
[20:52:43.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:74:0)
[20:52:43.270] Timer tick, asking Buildbucket for the build status
[20:52:43.487] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:43.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:75:0) after 1m0s
[20:53:43.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:75:0)
[20:53:43.518] Timer tick, asking Buildbucket for the build status
[20:53:43.895] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:43.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:76:0) after 1m0s
[20:54:43.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:76:0)
[20:54:43.922] Timer tick, asking Buildbucket for the build status
[20:54:44.003] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:44.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:77:0) after 1m0s
[20:55:44.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:77:0)
[20:55:44.099] Timer tick, asking Buildbucket for the build status
[20:55:44.182] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:44.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:78:0) after 1m0s
[20:56:44.261] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:78:0)
[20:56:44.261] Timer tick, asking Buildbucket for the build status
[20:56:44.479] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:44.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:79:0) after 1m0s
[20:57:44.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:79:0)
[20:57:44.611] Timer tick, asking Buildbucket for the build status
[20:57:44.713] Build 8867070498170983264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:44.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032500560263022976:80:0) after 1m0s
[20:58:11.051] Received PubSub notification, asking Buildbucket for the build status
[20:58:11.723] Build 8867070498170983264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:58:11.723] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1602104290153581",
"created_by": "project:emscripten-releases",
"created_ts": "1602099626338915",
"id": "8867070498170983264",
"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\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"title\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"title\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-4d1f\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"72abc72d3c27961c2055010ab5dd5b24edc1ee65\"}, \"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-4d1f\"], \"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\": [\"5570-fa4adf0\"], \"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": "1602099687020646",
"status": "COMPLETED",
"status_changed_ts": "1602104290760417",
"tags": [
"builder:win",
"buildset:commit/git/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/72abc72d3c27961c2055010ab5dd5b24edc1ee65",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032500560263022976",
"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/8867070498170983264/+/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:4f1d65cd96642710",
"user_agent:luci-scheduler"
],
"updated_ts": "1602104290770216",
"url": "https://ci.chromium.org/b/8867070498170983264",
"utcnow_ts": "1602104291709192"
}
}
[20:58:11.723] Invocation finished in 1h17m46.762645637s with status SUCCEEDED