[17:54:30.858] New invocation is queued and will start shortly
[17:54:32.379] Starting the invocation (attempt 1)
[17:54:32.434] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:54:32.434] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:54:32.434] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9063581982517095344",
"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\":\"cb7fa497617a60338af83b2624c3664fd7c43f71\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@cb7fa497617a60338af83b2624c3664fd7c43f71\",\"title\":\"cb7fa497617a60338af83b2624c3664fd7c43f71\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"cb7fa497617a60338af83b2624c3664fd7c43f71\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9063581982517095344",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/cb7fa497617a60338af83b2624c3664fd7c43f71",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71",
"gitiles_ref:refs/heads/master"
]
}
[17:54:33.502] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1572458072602430",
"id": "8898151920021454448",
"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\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@cb7fa497617a60338af83b2624c3664fd7c43f71\", \"title\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572458073272225",
"tags": [
"builder:win",
"buildset:commit/git/cb7fa497617a60338af83b2624c3664fd7c43f71",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063581982517095344",
"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/8898151920021454448/+/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": "1572458073272422",
"url": "https://ci.chromium.org/b/8898151920021454448",
"utcnow_ts": "1572458073491176"
}
}
[17:54:33.502] Task URL: https://ci.chromium.org/b/8898151920021454448
[17:54:33.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:2:0) after 1m0s
[17:55:33.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:2:0)
[17:55:33.586] Timer tick, asking Buildbucket for the build status
[17:55:34.065] Build 8898151920021454448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:34.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:3:0) after 1m0s
[17:55:39.142] Received PubSub notification, asking Buildbucket for the build status
[17:55:39.269] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:34.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:3:0)
[17:56:34.138] Timer tick, asking Buildbucket for the build status
[17:56:34.714] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:34.714] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:5:0) after 1m0s
[17:57:34.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:5:0)
[17:57:34.781] Timer tick, asking Buildbucket for the build status
[17:57:35.141] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:35.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:6:0) after 1m0s
[17:58:35.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:6:0)
[17:58:35.257] Timer tick, asking Buildbucket for the build status
[17:58:35.385] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:35.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:7:0) after 1m0s
[17:59:35.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:7:0)
[17:59:35.439] Timer tick, asking Buildbucket for the build status
[17:59:35.605] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:35.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:8:0) after 1m0s
[18:00:35.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:8:0)
[18:00:35.645] Timer tick, asking Buildbucket for the build status
[18:00:36.128] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:36.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:9:0) after 1m0s
[18:01:36.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:9:0)
[18:01:36.135] Timer tick, asking Buildbucket for the build status
[18:01:36.300] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:10:0) after 1m0s
[18:02:36.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:10:0)
[18:02:36.431] Timer tick, asking Buildbucket for the build status
[18:02:36.791] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:36.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:11:0) after 1m0s
[18:03:36.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:11:0)
[18:03:36.833] Timer tick, asking Buildbucket for the build status
[18:03:36.911] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:36.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:12:0) after 1m0s
[18:04:36.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:12:0)
[18:04:36.992] Timer tick, asking Buildbucket for the build status
[18:04:39.497] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:39.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:13:0) after 1m0s
[18:05:39.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:13:0)
[18:05:39.569] Timer tick, asking Buildbucket for the build status
[18:05:40.557] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:40.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:14:0) after 1m0s
[18:06:40.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:14:0)
[18:06:40.602] Timer tick, asking Buildbucket for the build status
[18:06:40.684] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:40.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:15:0) after 1m0s
[18:07:40.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:15:0)
[18:07:40.747] Timer tick, asking Buildbucket for the build status
[18:07:40.850] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:40.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:16:0) after 1m0s
[18:08:40.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:16:0)
[18:08:40.942] Timer tick, asking Buildbucket for the build status
[18:08:41.126] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:41.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:17:0) after 1m0s
[18:09:41.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:17:0)
[18:09:41.274] Timer tick, asking Buildbucket for the build status
[18:09:41.393] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:41.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:18:0) after 1m0s
[18:10:41.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:18:0)
[18:10:41.658] Timer tick, asking Buildbucket for the build status
[18:10:41.733] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:41.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:19:0) after 1m0s
[18:11:41.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:19:0)
[18:11:41.988] Timer tick, asking Buildbucket for the build status
[18:11:42.189] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:42.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:20:0) after 1m0s
[18:12:42.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:20:0)
[18:12:42.432] Timer tick, asking Buildbucket for the build status
[18:12:42.492] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:42.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:21:0) after 1m0s
[18:13:42.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:21:0)
[18:13:42.597] Timer tick, asking Buildbucket for the build status
[18:13:42.710] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:42.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:22:0) after 1m0s
[18:14:42.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:22:0)
[18:14:42.822] Timer tick, asking Buildbucket for the build status
[18:14:43.141] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:43.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:23:0) after 1m0s
[18:15:43.312] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:23:0)
[18:15:43.312] Timer tick, asking Buildbucket for the build status
[18:15:43.521] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:43.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:24:0) after 1m0s
[18:16:43.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:24:0)
[18:16:43.656] Timer tick, asking Buildbucket for the build status
[18:16:43.785] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:43.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:25:0) after 1m0s
[18:17:43.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:25:0)
[18:17:43.842] Timer tick, asking Buildbucket for the build status
[18:17:44.251] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:44.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:26:0) after 1m0s
[18:18:44.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:26:0)
[18:18:44.267] Timer tick, asking Buildbucket for the build status
[18:18:44.800] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:44.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:27:0) after 1m0s
[18:19:44.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:27:0)
[18:19:44.995] Timer tick, asking Buildbucket for the build status
[18:19:46.038] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:46.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:28:0) after 1m0s
[18:20:46.100] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:28:0)
[18:20:46.100] Timer tick, asking Buildbucket for the build status
[18:20:46.408] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:46.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:29:0) after 1m0s
[18:21:46.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:29:0)
[18:21:46.479] Timer tick, asking Buildbucket for the build status
[18:21:46.763] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:46.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:30:0) after 1m0s
[18:22:46.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:30:0)
[18:22:46.827] Timer tick, asking Buildbucket for the build status
[18:22:47.138] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:47.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:31:0) after 1m0s
[18:23:47.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:31:0)
[18:23:47.188] Timer tick, asking Buildbucket for the build status
[18:23:47.612] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:47.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:32:0) after 1m0s
[18:24:47.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:32:0)
[18:24:47.653] Timer tick, asking Buildbucket for the build status
[18:24:47.917] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:47.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:33:0) after 1m0s
[18:25:47.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:33:0)
[18:25:47.965] Timer tick, asking Buildbucket for the build status
[18:25:48.097] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:48.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:34:0) after 1m0s
[18:26:48.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:34:0)
[18:26:48.188] Timer tick, asking Buildbucket for the build status
[18:26:48.278] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:48.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:35:0) after 1m0s
[18:27:48.315] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:35:0)
[18:27:48.315] Timer tick, asking Buildbucket for the build status
[18:27:48.501] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:48.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:36:0) after 1m0s
[18:28:48.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:36:0)
[18:28:48.532] Timer tick, asking Buildbucket for the build status
[18:28:49.021] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:49.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:37:0) after 1m0s
[18:29:50.455] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:37:0)
[18:29:50.455] Timer tick, asking Buildbucket for the build status
[18:29:50.742] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:50.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:38:0) after 1m0s
[18:30:50.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:38:0)
[18:30:50.969] Timer tick, asking Buildbucket for the build status
[18:30:51.062] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:51.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:39:0) after 1m0s
[18:31:51.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:39:0)
[18:31:51.561] Timer tick, asking Buildbucket for the build status
[18:31:52.010] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:52.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:40:0) after 1m0s
[18:32:52.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:40:0)
[18:32:52.396] Timer tick, asking Buildbucket for the build status
[18:32:52.461] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:52.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:41:0) after 1m0s
[18:33:52.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:41:0)
[18:33:52.549] Timer tick, asking Buildbucket for the build status
[18:33:52.742] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:52.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:42:0) after 1m0s
[18:34:52.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:42:0)
[18:34:52.778] Timer tick, asking Buildbucket for the build status
[18:34:53.007] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:53.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:43:0) after 1m0s
[18:35:53.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:43:0)
[18:35:53.097] Timer tick, asking Buildbucket for the build status
[18:35:53.353] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:53.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:44:0) after 1m0s
[18:36:53.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:44:0)
[18:36:53.392] Timer tick, asking Buildbucket for the build status
[18:36:53.529] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:53.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:45:0) after 1m0s
[18:37:53.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:45:0)
[18:37:53.557] Timer tick, asking Buildbucket for the build status
[18:37:53.698] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:53.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:46:0) after 1m0s
[18:38:53.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:46:0)
[18:38:53.940] Timer tick, asking Buildbucket for the build status
[18:38:54.150] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:54.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:47:0) after 1m0s
[18:39:54.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:47:0)
[18:39:54.195] Timer tick, asking Buildbucket for the build status
[18:39:54.305] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:54.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:48:0) after 1m0s
[18:40:54.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:48:0)
[18:40:54.322] Timer tick, asking Buildbucket for the build status
[18:40:54.477] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:54.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:49:0) after 1m0s
[18:41:54.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:49:0)
[18:41:54.515] Timer tick, asking Buildbucket for the build status
[18:41:54.593] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:54.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:50:0) after 1m0s
[18:42:54.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:50:0)
[18:42:54.616] Timer tick, asking Buildbucket for the build status
[18:42:54.932] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:54.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:51:0) after 1m0s
[18:43:55.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:51:0)
[18:43:55.015] Timer tick, asking Buildbucket for the build status
[18:43:55.787] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:55.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:52:0) after 1m0s
[18:44:55.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:52:0)
[18:44:55.815] Timer tick, asking Buildbucket for the build status
[18:44:56.057] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:56.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:53:0) after 1m0s
[18:45:56.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:53:0)
[18:45:56.114] Timer tick, asking Buildbucket for the build status
[18:45:56.293] Build 8898151920021454448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:56.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063581982517095344:54:0) after 1m0s
[18:46:30.230] Received PubSub notification, asking Buildbucket for the build status
[18:46:30.556] Build 8898151920021454448: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:46:30.556] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1572461189139212",
"created_by": "project:emscripten-releases",
"created_ts": "1572458072602430",
"id": "8898151920021454448",
"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\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@cb7fa497617a60338af83b2624c3664fd7c43f71\", \"title\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}}",
"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\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@cb7fa497617a60338af83b2624c3664fd7c43f71\", \"title\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-9miq\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"cb7fa497617a60338af83b2624c3664fd7c43f71\"}, \"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-9miq\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4594-a77d3be\"], \"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": "1572458137628623",
"status": "COMPLETED",
"status_changed_ts": "1572461189794121",
"tags": [
"builder:win",
"buildset:commit/git/cb7fa497617a60338af83b2624c3664fd7c43f71",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/cb7fa497617a60338af83b2624c3664fd7c43f71",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063581982517095344",
"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/8898151920021454448/+/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:4836a084b0d4e510",
"user_agent:luci-scheduler"
],
"updated_ts": "1572461189870450",
"url": "https://ci.chromium.org/b/8898151920021454448",
"utcnow_ts": "1572461190523640"
}
}
[18:46:30.556] Invocation finished in 51m59.716924522s with status SUCCEEDED