[19:56:40.268] New invocation is queued and will start shortly
[19:56:41.633] Starting the invocation (attempt 1)
[19:56:41.726] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:56:41.726] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:56:41.726] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9044639531091784800",
"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\":\"9fd90d035d2f143f13447abb2839927c01742d94\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9fd90d035d2f143f13447abb2839927c01742d94\",\"title\":\"9fd90d035d2f143f13447abb2839927c01742d94\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"9fd90d035d2f143f13447abb2839927c01742d94\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9044639531091784800",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/9fd90d035d2f143f13447abb2839927c01742d94",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94",
"gitiles_ref:refs/heads/master"
]
}
[19:56:43.929] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1590523001974164",
"id": "8879209468640434704",
"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\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9fd90d035d2f143f13447abb2839927c01742d94\", \"title\": \"9fd90d035d2f143f13447abb2839927c01742d94\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590523003384956",
"tags": [
"builder:win",
"buildset:commit/git/9fd90d035d2f143f13447abb2839927c01742d94",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044639531091784800",
"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/8879209468640434704/+/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": "1590523003385215",
"url": "https://ci.chromium.org/b/8879209468640434704",
"utcnow_ts": "1590523003913882"
}
}
[19:56:43.929] Task URL: https://ci.chromium.org/b/8879209468640434704
[19:56:43.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:2:0) after 1m0s
[19:57:44.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:2:0)
[19:57:44.095] Timer tick, asking Buildbucket for the build status
[19:57:44.428] Build 8879209468640434704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:44.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:3:0) after 1m0s
[19:57:48.138] Received PubSub notification, asking Buildbucket for the build status
[19:57:48.289] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:44.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:3:0)
[19:58:44.611] Timer tick, asking Buildbucket for the build status
[19:58:45.183] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:45.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:5:0) after 1m0s
[19:59:45.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:5:0)
[19:59:45.336] Timer tick, asking Buildbucket for the build status
[19:59:45.504] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:45.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:6:0) after 1m0s
[20:00:45.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:6:0)
[20:00:45.600] Timer tick, asking Buildbucket for the build status
[20:00:46.644] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:46.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:7:0) after 1m0s
[20:01:46.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:7:0)
[20:01:46.686] Timer tick, asking Buildbucket for the build status
[20:01:48.484] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:48.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:8:0) after 1m0s
[20:02:48.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:8:0)
[20:02:48.605] Timer tick, asking Buildbucket for the build status
[20:02:49.964] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:9:0) after 1m0s
[20:03:50.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:9:0)
[20:03:50.052] Timer tick, asking Buildbucket for the build status
[20:03:50.307] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:50.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:10:0) after 1m0s
[20:04:50.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:10:0)
[20:04:50.409] Timer tick, asking Buildbucket for the build status
[20:04:51.113] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:51.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:11:0) after 1m0s
[20:05:51.163] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:11:0)
[20:05:51.163] Timer tick, asking Buildbucket for the build status
[20:05:51.811] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:51.811] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:12:0) after 1m0s
[20:06:51.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:12:0)
[20:06:51.949] Timer tick, asking Buildbucket for the build status
[20:06:52.111] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:52.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:13:0) after 1m0s
[20:07:52.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:13:0)
[20:07:52.214] Timer tick, asking Buildbucket for the build status
[20:07:52.668] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:52.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:14:0) after 1m0s
[20:08:52.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:14:0)
[20:08:52.959] Timer tick, asking Buildbucket for the build status
[20:08:53.236] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:53.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:15:0) after 1m0s
[20:09:53.298] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:15:0)
[20:09:53.298] Timer tick, asking Buildbucket for the build status
[20:09:54.439] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:54.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:16:0) after 1m0s
[20:10:54.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:16:0)
[20:10:54.533] Timer tick, asking Buildbucket for the build status
[20:10:55.006] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:55.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:17:0) after 1m0s
[20:11:55.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:17:0)
[20:11:55.035] Timer tick, asking Buildbucket for the build status
[20:11:55.310] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:55.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:18:0) after 1m0s
[20:12:55.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:18:0)
[20:12:55.437] Timer tick, asking Buildbucket for the build status
[20:12:55.871] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:55.871] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:19:0) after 1m0s
[20:13:55.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:19:0)
[20:13:55.860] Timer tick, asking Buildbucket for the build status
[20:13:55.981] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:55.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:20:0) after 1m0s
[20:14:56.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:20:0)
[20:14:56.027] Timer tick, asking Buildbucket for the build status
[20:14:56.313] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:56.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:21:0) after 1m0s
[20:15:56.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:21:0)
[20:15:56.363] Timer tick, asking Buildbucket for the build status
[20:15:56.588] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:56.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:22:0) after 1m0s
[20:16:56.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:22:0)
[20:16:56.633] Timer tick, asking Buildbucket for the build status
[20:16:58.258] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:58.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:23:0) after 1m0s
[20:17:58.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:23:0)
[20:17:58.281] Timer tick, asking Buildbucket for the build status
[20:17:58.860] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:58.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:24:0) after 1m0s
[20:19:00.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:24:0)
[20:19:00.528] Timer tick, asking Buildbucket for the build status
[20:19:00.787] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:00.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:25:0) after 1m0s
[20:20:01.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:25:0)
[20:20:01.186] Timer tick, asking Buildbucket for the build status
[20:20:01.481] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:01.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:26:0) after 1m0s
[20:21:01.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:26:0)
[20:21:01.679] Timer tick, asking Buildbucket for the build status
[20:21:01.799] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:01.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:27:0) after 1m0s
[20:22:01.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:27:0)
[20:22:01.833] Timer tick, asking Buildbucket for the build status
[20:22:02.121] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:02.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:28:0) after 1m0s
[20:23:02.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:28:0)
[20:23:02.257] Timer tick, asking Buildbucket for the build status
[20:23:02.647] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:02.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:29:0) after 1m0s
[20:24:02.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:29:0)
[20:24:02.671] Timer tick, asking Buildbucket for the build status
[20:24:02.823] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:02.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:30:0) after 1m0s
[20:25:02.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:30:0)
[20:25:02.870] Timer tick, asking Buildbucket for the build status
[20:25:05.596] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:05.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:31:0) after 1m0s
[20:26:05.684] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:31:0)
[20:26:05.684] Timer tick, asking Buildbucket for the build status
[20:26:07.506] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:07.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:32:0) after 1m0s
[20:27:07.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:32:0)
[20:27:07.526] Timer tick, asking Buildbucket for the build status
[20:27:07.741] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:07.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:33:0) after 1m0s
[20:28:07.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:33:0)
[20:28:07.812] Timer tick, asking Buildbucket for the build status
[20:28:08.741] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:08.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:34:0) after 1m0s
[20:29:08.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:34:0)
[20:29:08.766] Timer tick, asking Buildbucket for the build status
[20:29:11.006] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:11.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:35:0) after 1m0s
[20:30:11.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:35:0)
[20:30:11.079] Timer tick, asking Buildbucket for the build status
[20:30:14.457] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:14.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:36:0) after 1m0s
[20:31:14.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:36:0)
[20:31:14.480] Timer tick, asking Buildbucket for the build status
[20:31:14.868] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:14.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:37:0) after 1m0s
[20:32:14.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:37:0)
[20:32:14.911] Timer tick, asking Buildbucket for the build status
[20:32:15.472] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:15.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:38:0) after 1m0s
[20:33:15.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:38:0)
[20:33:15.513] Timer tick, asking Buildbucket for the build status
[20:33:18.951] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:18.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:39:0) after 1m0s
[20:34:19.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:39:0)
[20:34:19.108] Timer tick, asking Buildbucket for the build status
[20:34:19.242] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:40:0) after 1m0s
[20:35:19.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:40:0)
[20:35:19.289] Timer tick, asking Buildbucket for the build status
[20:35:19.519] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:19.519] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:41:0) after 1m0s
[20:36:19.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:41:0)
[20:36:19.693] Timer tick, asking Buildbucket for the build status
[20:36:20.062] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:20.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:42:0) after 1m0s
[20:37:20.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:42:0)
[20:37:20.202] Timer tick, asking Buildbucket for the build status
[20:37:20.629] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:20.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:43:0) after 1m0s
[20:38:20.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:43:0)
[20:38:20.694] Timer tick, asking Buildbucket for the build status
[20:38:20.923] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:20.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:44:0) after 1m0s
[20:39:21.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:44:0)
[20:39:21.035] Timer tick, asking Buildbucket for the build status
[20:39:21.835] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:21.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:45:0) after 1m0s
[20:40:21.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:45:0)
[20:40:21.859] Timer tick, asking Buildbucket for the build status
[20:40:23.236] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:23.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:46:0) after 1m0s
[20:41:23.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:46:0)
[20:41:23.271] Timer tick, asking Buildbucket for the build status
[20:41:23.510] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:23.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:47:0) after 1m0s
[20:42:23.587] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:47:0)
[20:42:23.588] Timer tick, asking Buildbucket for the build status
[20:42:23.834] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:23.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:48:0) after 1m0s
[20:43:23.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:48:0)
[20:43:23.921] Timer tick, asking Buildbucket for the build status
[20:43:25.421] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:25.421] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:49:0) after 1m0s
[20:44:25.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:49:0)
[20:44:25.471] Timer tick, asking Buildbucket for the build status
[20:44:25.973] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:25.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:50:0) after 1m0s
[20:45:26.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:50:0)
[20:45:26.068] Timer tick, asking Buildbucket for the build status
[20:45:27.359] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:27.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:51:0) after 1m0s
[20:46:27.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:51:0)
[20:46:27.397] Timer tick, asking Buildbucket for the build status
[20:46:27.681] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:27.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:52:0) after 1m0s
[20:47:27.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:52:0)
[20:47:27.735] Timer tick, asking Buildbucket for the build status
[20:47:28.062] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:28.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:53:0) after 1m0s
[20:48:28.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:53:0)
[20:48:28.089] Timer tick, asking Buildbucket for the build status
[20:48:28.535] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:28.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:54:0) after 1m0s
[20:49:28.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:54:0)
[20:49:28.601] Timer tick, asking Buildbucket for the build status
[20:49:28.799] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:28.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:55:0) after 1m0s
[20:50:28.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:55:0)
[20:50:28.832] Timer tick, asking Buildbucket for the build status
[20:50:29.127] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:29.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:56:0) after 1m0s
[20:51:29.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:56:0)
[20:51:29.133] Timer tick, asking Buildbucket for the build status
[20:51:29.337] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:29.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:57:0) after 1m0s
[20:52:29.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:57:0)
[20:52:29.362] Timer tick, asking Buildbucket for the build status
[20:52:29.582] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:29.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:58:0) after 1m0s
[20:53:29.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:58:0)
[20:53:29.702] Timer tick, asking Buildbucket for the build status
[20:53:29.869] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:29.869] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:59:0) after 1m0s
[20:54:30.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:59:0)
[20:54:30.017] Timer tick, asking Buildbucket for the build status
[20:54:30.744] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:30.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:60:0) after 1m0s
[20:55:30.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:60:0)
[20:55:30.953] Timer tick, asking Buildbucket for the build status
[20:55:31.222] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:31.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:61:0) after 1m0s
[20:56:31.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:61:0)
[20:56:31.257] Timer tick, asking Buildbucket for the build status
[20:56:31.621] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:31.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:62:0) after 1m0s
[20:57:31.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:62:0)
[20:57:31.836] Timer tick, asking Buildbucket for the build status
[20:57:32.999] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:32.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:63:0) after 1m0s
[20:58:33.100] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:63:0)
[20:58:33.100] Timer tick, asking Buildbucket for the build status
[20:58:33.997] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:33.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:64:0) after 1m0s
[20:59:34.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:64:0)
[20:59:34.097] Timer tick, asking Buildbucket for the build status
[20:59:34.495] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:34.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:65:0) after 1m0s
[21:00:34.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:65:0)
[21:00:34.625] Timer tick, asking Buildbucket for the build status
[21:00:35.458] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:35.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:66:0) after 1m0s
[21:01:35.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:66:0)
[21:01:35.449] Timer tick, asking Buildbucket for the build status
[21:01:35.972] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:67:0) after 1m0s
[21:02:35.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:67:0)
[21:02:35.990] Timer tick, asking Buildbucket for the build status
[21:02:36.529] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:36.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:68:0) after 1m0s
[21:03:36.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:68:0)
[21:03:36.585] Timer tick, asking Buildbucket for the build status
[21:03:36.971] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:36.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:69:0) after 1m0s
[21:04:37.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:69:0)
[21:04:37.016] Timer tick, asking Buildbucket for the build status
[21:04:37.142] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:37.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:70:0) after 1m0s
[21:05:37.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:70:0)
[21:05:37.209] Timer tick, asking Buildbucket for the build status
[21:05:38.402] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:38.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:71:0) after 1m0s
[21:06:38.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:71:0)
[21:06:38.458] Timer tick, asking Buildbucket for the build status
[21:06:38.642] Build 8879209468640434704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:38.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9044639531091784800:72:0) after 1m0s
[21:07:31.788] Received PubSub notification, asking Buildbucket for the build status
[21:07:32.757] Build 8879209468640434704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:07:32.757] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1590527250493373",
"created_by": "project:emscripten-releases",
"created_ts": "1590523001974164",
"id": "8879209468640434704",
"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\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9fd90d035d2f143f13447abb2839927c01742d94\", \"title\": \"9fd90d035d2f143f13447abb2839927c01742d94\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}}",
"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\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9fd90d035d2f143f13447abb2839927c01742d94\", \"title\": \"9fd90d035d2f143f13447abb2839927c01742d94\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-oen6\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"9fd90d035d2f143f13447abb2839927c01742d94\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9fd90d035d2f143f13447abb2839927c01742d94\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-oen6\"], \"image\": [\"chrome-win10-1703-20042500-5e6af220c3f\"], \"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\": [\"5125-829145d\"], \"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": "1590523066689225",
"status": "COMPLETED",
"status_changed_ts": "1590527251007524",
"tags": [
"builder:win",
"buildset:commit/git/9fd90d035d2f143f13447abb2839927c01742d94",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9fd90d035d2f143f13447abb2839927c01742d94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044639531091784800",
"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/8879209468640434704/+/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:4c6b609aec1dfc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1590527251098316",
"url": "https://ci.chromium.org/b/8879209468640434704",
"utcnow_ts": "1590527252557036"
}
}
[21:07:32.757] Invocation finished in 1h10m52.504030569s with status SUCCEEDED