[19:34:10.396] New invocation is queued and will start shortly
[19:34:11.442] Starting the invocation (attempt 1)
[19:34:11.487] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:34:11.487] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:34:11.487] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9067652576003947520",
"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\":\"45a33b704fbb524bce5dda091a23457c978e9e4d\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45a33b704fbb524bce5dda091a23457c978e9e4d\",\"title\":\"45a33b704fbb524bce5dda091a23457c978e9e4d\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"45a33b704fbb524bce5dda091a23457c978e9e4d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9067652576003947520",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/45a33b704fbb524bce5dda091a23457c978e9e4d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d",
"gitiles_ref:refs/heads/master"
]
}
[19:34:12.890] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1568576051620027",
"id": "8902222514055174064",
"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\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45a33b704fbb524bce5dda091a23457c978e9e4d\", \"title\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568576052316919",
"tags": [
"builder:win",
"buildset:commit/git/45a33b704fbb524bce5dda091a23457c978e9e4d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067652576003947520",
"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/8902222514055174064/+/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": "1568576052317068",
"url": "https://ci.chromium.org/b/8902222514055174064",
"utcnow_ts": "1568576052796122"
}
}
[19:34:12.890] Task URL: https://ci.chromium.org/b/8902222514055174064
[19:34:12.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:2:0) after 1m0s
[19:34:18.651] Received PubSub notification, asking Buildbucket for the build status
[19:34:18.744] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:12.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:2:0)
[19:35:12.868] Timer tick, asking Buildbucket for the build status
[19:35:12.971] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:12.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:4:0) after 1m0s
[19:36:13.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:4:0)
[19:36:13.005] Timer tick, asking Buildbucket for the build status
[19:36:13.353] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:13.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:5:0) after 1m0s
[19:37:13.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:5:0)
[19:37:13.755] Timer tick, asking Buildbucket for the build status
[19:37:14.464] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:14.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:6:0) after 1m0s
[19:38:14.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:6:0)
[19:38:14.538] Timer tick, asking Buildbucket for the build status
[19:38:14.684] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:14.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:7:0) after 1m0s
[19:39:14.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:7:0)
[19:39:14.796] Timer tick, asking Buildbucket for the build status
[19:39:16.345] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:16.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:8:0) after 1m0s
[19:40:16.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:8:0)
[19:40:16.353] Timer tick, asking Buildbucket for the build status
[19:40:16.518] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:16.519] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:9:0) after 1m0s
[19:41:16.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:9:0)
[19:41:16.728] Timer tick, asking Buildbucket for the build status
[19:41:17.055] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:17.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:10:0) after 1m0s
[19:42:17.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:10:0)
[19:42:17.198] Timer tick, asking Buildbucket for the build status
[19:42:17.306] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:17.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:11:0) after 1m0s
[19:43:17.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:11:0)
[19:43:17.437] Timer tick, asking Buildbucket for the build status
[19:43:17.579] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:17.579] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:12:0) after 1m0s
[19:44:17.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:12:0)
[19:44:17.602] Timer tick, asking Buildbucket for the build status
[19:44:18.441] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:18.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:13:0) after 1m0s
[19:45:18.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:13:0)
[19:45:18.527] Timer tick, asking Buildbucket for the build status
[19:45:19.322] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:19.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:14:0) after 1m0s
[19:46:19.352] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:14:0)
[19:46:19.352] Timer tick, asking Buildbucket for the build status
[19:46:19.555] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:19.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:15:0) after 1m0s
[19:47:19.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:15:0)
[19:47:19.650] Timer tick, asking Buildbucket for the build status
[19:47:21.586] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:21.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:16:0) after 1m0s
[19:48:21.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:16:0)
[19:48:21.686] Timer tick, asking Buildbucket for the build status
[19:48:21.860] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:21.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:17:0) after 1m0s
[19:49:22.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:17:0)
[19:49:22.013] Timer tick, asking Buildbucket for the build status
[19:49:22.415] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:22.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:18:0) after 1m0s
[19:50:22.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:18:0)
[19:50:22.616] Timer tick, asking Buildbucket for the build status
[19:50:23.033] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:23.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:19:0) after 1m0s
[19:51:23.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:19:0)
[19:51:23.098] Timer tick, asking Buildbucket for the build status
[19:51:23.300] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:23.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:20:0) after 1m0s
[19:52:23.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:20:0)
[19:52:23.337] Timer tick, asking Buildbucket for the build status
[19:52:23.618] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:23.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:21:0) after 1m0s
[19:53:23.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:21:0)
[19:53:23.645] Timer tick, asking Buildbucket for the build status
[19:53:23.822] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:23.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:22:0) after 1m0s
[19:54:23.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:22:0)
[19:54:23.853] Timer tick, asking Buildbucket for the build status
[19:54:24.056] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:24.056] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:23:0) after 1m0s
[19:55:24.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:23:0)
[19:55:24.120] Timer tick, asking Buildbucket for the build status
[19:55:24.303] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:24.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:24:0) after 1m0s
[19:56:24.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:24:0)
[19:56:24.394] Timer tick, asking Buildbucket for the build status
[19:56:24.896] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:24.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:25:0) after 1m0s
[19:57:24.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:25:0)
[19:57:24.928] Timer tick, asking Buildbucket for the build status
[19:57:25.165] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:25.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:26:0) after 1m0s
[19:58:25.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:26:0)
[19:58:25.350] Timer tick, asking Buildbucket for the build status
[19:58:25.572] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:25.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:27:0) after 1m0s
[19:59:25.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:27:0)
[19:59:25.597] Timer tick, asking Buildbucket for the build status
[19:59:25.725] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:25.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:28:0) after 1m0s
[20:00:25.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:28:0)
[20:00:25.794] Timer tick, asking Buildbucket for the build status
[20:00:25.970] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:25.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:29:0) after 1m0s
[20:01:26.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:29:0)
[20:01:26.079] Timer tick, asking Buildbucket for the build status
[20:01:26.204] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:30:0) after 1m0s
[20:02:26.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:30:0)
[20:02:26.235] Timer tick, asking Buildbucket for the build status
[20:02:26.863] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:26.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:31:0) after 1m0s
[20:03:26.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:31:0)
[20:03:26.994] Timer tick, asking Buildbucket for the build status
[20:03:27.144] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:27.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:32:0) after 1m0s
[20:04:27.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:32:0)
[20:04:27.199] Timer tick, asking Buildbucket for the build status
[20:04:27.575] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:27.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:33:0) after 1m0s
[20:05:27.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:33:0)
[20:05:27.618] Timer tick, asking Buildbucket for the build status
[20:05:27.981] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:27.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:34:0) after 1m0s
[20:06:28.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:34:0)
[20:06:28.176] Timer tick, asking Buildbucket for the build status
[20:06:29.190] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:29.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:35:0) after 1m0s
[20:07:29.492] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:35:0)
[20:07:29.492] Timer tick, asking Buildbucket for the build status
[20:07:30.535] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:30.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:36:0) after 1m0s
[20:08:30.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:36:0)
[20:08:30.798] Timer tick, asking Buildbucket for the build status
[20:08:30.867] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:37:0) after 1m0s
[20:09:31.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:37:0)
[20:09:31.011] Timer tick, asking Buildbucket for the build status
[20:09:31.171] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:31.171] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:38:0) after 1m0s
[20:10:31.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:38:0)
[20:10:31.337] Timer tick, asking Buildbucket for the build status
[20:10:31.454] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:31.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:39:0) after 1m0s
[20:11:31.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:39:0)
[20:11:31.585] Timer tick, asking Buildbucket for the build status
[20:11:31.753] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:31.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:40:0) after 1m0s
[20:12:31.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:40:0)
[20:12:31.796] Timer tick, asking Buildbucket for the build status
[20:12:34.266] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:34.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:41:0) after 1m0s
[20:13:34.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:41:0)
[20:13:34.299] Timer tick, asking Buildbucket for the build status
[20:13:34.452] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:34.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:42:0) after 1m0s
[20:14:34.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:42:0)
[20:14:34.530] Timer tick, asking Buildbucket for the build status
[20:14:34.664] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:34.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:43:0) after 1m0s
[20:15:34.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:43:0)
[20:15:34.694] Timer tick, asking Buildbucket for the build status
[20:15:34.851] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:34.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:44:0) after 1m0s
[20:16:34.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:44:0)
[20:16:34.888] Timer tick, asking Buildbucket for the build status
[20:16:35.331] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:35.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:45:0) after 1m0s
[20:17:35.410] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:45:0)
[20:17:35.410] Timer tick, asking Buildbucket for the build status
[20:17:35.637] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:35.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:46:0) after 1m0s
[20:18:35.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:46:0)
[20:18:35.662] Timer tick, asking Buildbucket for the build status
[20:18:35.993] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:35.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:47:0) after 1m0s
[20:19:36.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:47:0)
[20:19:36.024] Timer tick, asking Buildbucket for the build status
[20:19:36.197] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:36.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:48:0) after 1m0s
[20:20:36.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:48:0)
[20:20:36.342] Timer tick, asking Buildbucket for the build status
[20:20:36.530] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:36.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:49:0) after 1m0s
[20:21:36.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:49:0)
[20:21:36.558] Timer tick, asking Buildbucket for the build status
[20:21:36.653] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:36.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:50:0) after 1m0s
[20:22:36.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:50:0)
[20:22:36.784] Timer tick, asking Buildbucket for the build status
[20:22:36.951] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:36.952] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:51:0) after 1m0s
[20:23:37.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:51:0)
[20:23:37.051] Timer tick, asking Buildbucket for the build status
[20:23:37.165] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:37.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:52:0) after 1m0s
[20:24:37.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:52:0)
[20:24:37.141] Timer tick, asking Buildbucket for the build status
[20:24:37.255] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:37.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:53:0) after 1m0s
[20:25:37.283] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:53:0)
[20:25:37.283] Timer tick, asking Buildbucket for the build status
[20:25:37.404] Build 8902222514055174064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:37.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9067652576003947520:54:0) after 1m0s
[20:25:52.889] Received PubSub notification, asking Buildbucket for the build status
[20:25:52.938] Build 8902222514055174064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:25:52.938] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1568579151944098",
"created_by": "project:emscripten-releases",
"created_ts": "1568576051620027",
"id": "8902222514055174064",
"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\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45a33b704fbb524bce5dda091a23457c978e9e4d\", \"title\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}}",
"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\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45a33b704fbb524bce5dda091a23457c978e9e4d\", \"title\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-4h36\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45a33b704fbb524bce5dda091a23457c978e9e4d\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-4h36\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4436-e523514\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568576056080380",
"status": "COMPLETED",
"status_changed_ts": "1568579152360373",
"tags": [
"builder:win",
"buildset:commit/git/45a33b704fbb524bce5dda091a23457c978e9e4d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45a33b704fbb524bce5dda091a23457c978e9e4d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067652576003947520",
"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/8902222514055174064/+/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:474f3cacb7bbbc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1568579152377722",
"url": "https://ci.chromium.org/b/8902222514055174064",
"utcnow_ts": "1568579152931460"
}
}
[20:25:52.938] Invocation finished in 51m42.560104946s with status SUCCEEDED