[17:20:25.092] New invocation is queued and will start shortly
[17:20:26.302] Starting the invocation (attempt 1)
[17:20:26.381] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:20:26.381] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:20:26.382] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9019101017151152496",
"parameters_json": "{\"builder_name\":\"mac\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"16214165f9c626eba2effc2d7c6b70470fe88084\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@16214165f9c626eba2effc2d7c6b70470fe88084\",\"title\":\"16214165f9c626eba2effc2d7c6b70470fe88084\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"16214165f9c626eba2effc2d7c6b70470fe88084\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:mac",
"scheduler_invocation_id:9019101017151152496",
"scheduler_job_id:emscripten-releases/mac",
"user_agent:luci-scheduler",
"buildset:commit/git/16214165f9c626eba2effc2d7c6b70470fe88084",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084",
"gitiles_ref:refs/heads/master"
]
}
[17:20:27.014] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1614878426566584",
"id": "8853670954942953056",
"parameters_json": "{\"builder_name\": \"mac\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@16214165f9c626eba2effc2d7c6b70470fe88084\", \"title\": \"16214165f9c626eba2effc2d7c6b70470fe88084\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614878426838161",
"tags": [
"builder:mac",
"buildset:commit/git/16214165f9c626eba2effc2d7c6b70470fe88084",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019101017151152496",
"scheduler_job_id:emscripten-releases/mac",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8853670954942953056/+/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": "1614878426838268",
"url": "https://ci.chromium.org/b/8853670954942953056",
"utcnow_ts": "1614878427002785"
}
}
[17:20:27.014] Task URL: https://ci.chromium.org/b/8853670954942953056
[17:20:27.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:2:0) after 1m0s
[17:21:27.100] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:2:0)
[17:21:27.100] Timer tick, asking Buildbucket for the build status
[17:21:27.321] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:27.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:3:0) after 1m0s
[17:22:27.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:3:0)
[17:22:27.397] Timer tick, asking Buildbucket for the build status
[17:22:27.472] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:27.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:4:0) after 1m0s
[17:23:27.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:4:0)
[17:23:27.511] Timer tick, asking Buildbucket for the build status
[17:23:27.573] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:27.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:5:0) after 1m0s
[17:24:27.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:5:0)
[17:24:27.551] Timer tick, asking Buildbucket for the build status
[17:24:27.614] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:27.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:6:0) after 1m0s
[17:25:27.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:6:0)
[17:25:27.776] Timer tick, asking Buildbucket for the build status
[17:25:28.019] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:28.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:7:0) after 1m0s
[17:26:28.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:7:0)
[17:26:28.181] Timer tick, asking Buildbucket for the build status
[17:26:28.278] Build 8853670954942953056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:28.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:8:0) after 1m0s
[17:26:32.075] Received PubSub notification, asking Buildbucket for the build status
[17:26:32.350] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:28.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:8:0)
[17:27:28.389] Timer tick, asking Buildbucket for the build status
[17:27:28.508] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:28.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:10:0) after 1m0s
[17:28:28.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:10:0)
[17:28:28.915] Timer tick, asking Buildbucket for the build status
[17:28:28.996] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:28.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:11:0) after 1m0s
[17:29:29.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:11:0)
[17:29:29.133] Timer tick, asking Buildbucket for the build status
[17:29:29.198] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:29.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:12:0) after 1m0s
[17:30:29.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:12:0)
[17:30:29.219] Timer tick, asking Buildbucket for the build status
[17:30:31.603] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:31.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:13:0) after 1m0s
[17:31:31.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:13:0)
[17:31:31.632] Timer tick, asking Buildbucket for the build status
[17:31:31.861] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:31.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:14:0) after 1m0s
[17:32:31.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:14:0)
[17:32:31.959] Timer tick, asking Buildbucket for the build status
[17:32:32.889] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:32.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:15:0) after 1m0s
[17:33:32.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:15:0)
[17:33:32.985] Timer tick, asking Buildbucket for the build status
[17:33:33.067] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:33.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:16:0) after 1m0s
[17:34:33.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:16:0)
[17:34:33.058] Timer tick, asking Buildbucket for the build status
[17:34:33.127] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:33.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:17:0) after 1m0s
[17:35:33.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:17:0)
[17:35:33.164] Timer tick, asking Buildbucket for the build status
[17:35:33.263] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:33.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:18:0) after 1m0s
[17:36:33.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:18:0)
[17:36:33.292] Timer tick, asking Buildbucket for the build status
[17:36:33.517] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:33.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:19:0) after 1m0s
[17:37:33.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:19:0)
[17:37:33.573] Timer tick, asking Buildbucket for the build status
[17:37:33.703] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:33.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:20:0) after 1m0s
[17:38:33.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:20:0)
[17:38:33.729] Timer tick, asking Buildbucket for the build status
[17:38:33.839] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:33.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:21:0) after 1m0s
[17:39:33.850] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:21:0)
[17:39:33.850] Timer tick, asking Buildbucket for the build status
[17:39:33.967] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:33.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:22:0) after 1m0s
[17:40:33.986] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:22:0)
[17:40:33.986] Timer tick, asking Buildbucket for the build status
[17:40:34.195] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:34.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:23:0) after 1m0s
[17:41:34.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:23:0)
[17:41:34.258] Timer tick, asking Buildbucket for the build status
[17:41:34.336] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:34.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:24:0) after 1m0s
[17:42:34.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:24:0)
[17:42:34.499] Timer tick, asking Buildbucket for the build status
[17:42:34.586] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:34.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:25:0) after 1m0s
[17:43:34.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:25:0)
[17:43:34.676] Timer tick, asking Buildbucket for the build status
[17:43:34.794] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:34.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:26:0) after 1m0s
[17:44:34.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:26:0)
[17:44:34.825] Timer tick, asking Buildbucket for the build status
[17:44:35.361] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:35.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:27:0) after 1m0s
[17:45:35.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:27:0)
[17:45:35.444] Timer tick, asking Buildbucket for the build status
[17:45:35.809] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:35.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:28:0) after 1m0s
[17:46:35.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:28:0)
[17:46:35.872] Timer tick, asking Buildbucket for the build status
[17:46:35.938] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:35.938] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:29:0) after 1m0s
[17:47:36.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:29:0)
[17:47:36.016] Timer tick, asking Buildbucket for the build status
[17:47:36.079] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:36.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:30:0) after 1m0s
[17:48:36.084] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:30:0)
[17:48:36.084] Timer tick, asking Buildbucket for the build status
[17:48:36.151] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:36.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:31:0) after 1m0s
[17:49:36.194] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:31:0)
[17:49:36.194] Timer tick, asking Buildbucket for the build status
[17:49:36.266] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:36.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:32:0) after 1m0s
[17:50:36.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:32:0)
[17:50:36.300] Timer tick, asking Buildbucket for the build status
[17:50:36.477] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:36.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:33:0) after 1m0s
[17:51:36.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:33:0)
[17:51:36.638] Timer tick, asking Buildbucket for the build status
[17:51:36.697] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:36.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:34:0) after 1m0s
[17:52:37.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:34:0)
[17:52:37.198] Timer tick, asking Buildbucket for the build status
[17:52:37.566] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:37.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:35:0) after 1m0s
[17:53:37.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:35:0)
[17:53:37.762] Timer tick, asking Buildbucket for the build status
[17:53:37.826] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:37.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:36:0) after 1m0s
[17:54:39.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:36:0)
[17:54:39.293] Timer tick, asking Buildbucket for the build status
[17:54:39.381] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:39.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:37:0) after 1m0s
[17:55:39.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:37:0)
[17:55:39.470] Timer tick, asking Buildbucket for the build status
[17:55:39.557] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:38:0) after 1m0s
[17:56:39.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:38:0)
[17:56:39.637] Timer tick, asking Buildbucket for the build status
[17:56:39.711] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:39:0) after 1m0s
[17:57:39.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:39:0)
[17:57:39.712] Timer tick, asking Buildbucket for the build status
[17:57:39.803] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:39.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:40:0) after 1m0s
[17:58:39.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:40:0)
[17:58:39.865] Timer tick, asking Buildbucket for the build status
[17:58:39.977] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:39.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:41:0) after 1m0s
[17:59:40.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:41:0)
[17:59:40.266] Timer tick, asking Buildbucket for the build status
[17:59:40.337] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:40.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:42:0) after 1m0s
[18:00:40.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:42:0)
[18:00:40.456] Timer tick, asking Buildbucket for the build status
[18:00:40.562] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:40.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:43:0) after 1m0s
[18:01:40.636] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:43:0)
[18:01:40.636] Timer tick, asking Buildbucket for the build status
[18:01:40.707] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:40.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:44:0) after 1m0s
[18:02:40.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:44:0)
[18:02:40.793] Timer tick, asking Buildbucket for the build status
[18:02:40.861] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:40.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:45:0) after 1m0s
[18:03:41.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:45:0)
[18:03:41.655] Timer tick, asking Buildbucket for the build status
[18:03:41.743] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:41.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:46:0) after 1m0s
[18:04:42.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:46:0)
[18:04:42.221] Timer tick, asking Buildbucket for the build status
[18:04:42.458] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:42.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:47:0) after 1m0s
[18:05:42.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:47:0)
[18:05:42.528] Timer tick, asking Buildbucket for the build status
[18:05:42.715] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:42.715] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:48:0) after 1m0s
[18:06:42.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:48:0)
[18:06:42.753] Timer tick, asking Buildbucket for the build status
[18:06:44.229] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:44.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:49:0) after 1m0s
[18:07:44.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:49:0)
[18:07:44.235] Timer tick, asking Buildbucket for the build status
[18:07:44.307] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:44.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:50:0) after 1m0s
[18:08:44.359] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:50:0)
[18:08:44.359] Timer tick, asking Buildbucket for the build status
[18:08:44.514] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:44.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:51:0) after 1m0s
[18:09:44.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:51:0)
[18:09:44.544] Timer tick, asking Buildbucket for the build status
[18:09:44.609] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:44.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:52:0) after 1m0s
[18:10:44.711] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:52:0)
[18:10:44.711] Timer tick, asking Buildbucket for the build status
[18:10:46.326] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:46.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:53:0) after 1m0s
[18:11:46.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:53:0)
[18:11:46.320] Timer tick, asking Buildbucket for the build status
[18:11:46.398] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:46.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:54:0) after 1m0s
[18:12:46.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:54:0)
[18:12:46.406] Timer tick, asking Buildbucket for the build status
[18:12:46.516] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:46.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:55:0) after 1m0s
[18:13:46.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:55:0)
[18:13:46.570] Timer tick, asking Buildbucket for the build status
[18:13:46.784] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:46.784] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:56:0) after 1m0s
[18:14:46.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:56:0)
[18:14:46.756] Timer tick, asking Buildbucket for the build status
[18:14:47.099] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:47.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:57:0) after 1m0s
[18:15:47.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:57:0)
[18:15:47.103] Timer tick, asking Buildbucket for the build status
[18:15:47.262] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:47.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:58:0) after 1m0s
[18:16:47.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:58:0)
[18:16:47.345] Timer tick, asking Buildbucket for the build status
[18:16:47.433] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:47.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:59:0) after 1m0s
[18:17:47.927] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:59:0)
[18:17:47.927] Timer tick, asking Buildbucket for the build status
[18:17:48.352] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:48.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:60:0) after 1m0s
[18:18:48.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:60:0)
[18:18:48.824] Timer tick, asking Buildbucket for the build status
[18:18:48.894] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:48.894] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:61:0) after 1m0s
[18:19:48.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:61:0)
[18:19:48.919] Timer tick, asking Buildbucket for the build status
[18:19:48.986] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:48.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:62:0) after 1m0s
[18:20:49.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:62:0)
[18:20:49.044] Timer tick, asking Buildbucket for the build status
[18:20:49.425] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:49.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:63:0) after 1m0s
[18:21:49.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:63:0)
[18:21:49.420] Timer tick, asking Buildbucket for the build status
[18:21:49.577] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:49.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:64:0) after 1m0s
[18:22:49.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:64:0)
[18:22:49.693] Timer tick, asking Buildbucket for the build status
[18:22:49.844] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:49.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:65:0) after 1m0s
[18:23:49.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:65:0)
[18:23:49.868] Timer tick, asking Buildbucket for the build status
[18:23:49.967] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:49.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:66:0) after 1m0s
[18:24:50.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:66:0)
[18:24:50.032] Timer tick, asking Buildbucket for the build status
[18:24:50.111] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:50.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:67:0) after 1m0s
[18:25:50.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:67:0)
[18:25:50.928] Timer tick, asking Buildbucket for the build status
[18:25:51.123] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:51.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:68:0) after 1m0s
[18:26:51.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:68:0)
[18:26:51.417] Timer tick, asking Buildbucket for the build status
[18:26:51.548] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:51.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:69:0) after 1m0s
[18:27:51.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:69:0)
[18:27:51.766] Timer tick, asking Buildbucket for the build status
[18:27:52.621] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:52.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:70:0) after 1m0s
[18:28:52.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:70:0)
[18:28:52.637] Timer tick, asking Buildbucket for the build status
[18:28:52.739] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:52.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:71:0) after 1m0s
[18:29:52.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:71:0)
[18:29:52.756] Timer tick, asking Buildbucket for the build status
[18:29:52.961] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:52.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:72:0) after 1m0s
[18:30:52.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:72:0)
[18:30:52.992] Timer tick, asking Buildbucket for the build status
[18:30:53.509] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:53.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:73:0) after 1m0s
[18:31:53.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:73:0)
[18:31:53.604] Timer tick, asking Buildbucket for the build status
[18:31:53.771] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:53.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:74:0) after 1m0s
[18:32:53.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:74:0)
[18:32:53.901] Timer tick, asking Buildbucket for the build status
[18:32:54.083] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:54.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:75:0) after 1m0s
[18:33:54.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:75:0)
[18:33:54.125] Timer tick, asking Buildbucket for the build status
[18:33:54.356] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:54.356] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:76:0) after 1m0s
[18:34:54.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:76:0)
[18:34:54.427] Timer tick, asking Buildbucket for the build status
[18:34:54.494] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:54.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:77:0) after 1m0s
[18:35:55.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:77:0)
[18:35:55.028] Timer tick, asking Buildbucket for the build status
[18:35:55.136] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:55.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:78:0) after 1m0s
[18:36:55.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:78:0)
[18:36:55.511] Timer tick, asking Buildbucket for the build status
[18:36:55.727] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:55.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:79:0) after 1m0s
[18:37:55.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:79:0)
[18:37:55.860] Timer tick, asking Buildbucket for the build status
[18:37:55.936] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:55.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:80:0) after 1m0s
[18:38:56.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:80:0)
[18:38:56.195] Timer tick, asking Buildbucket for the build status
[18:38:56.279] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:56.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:81:0) after 1m0s
[18:39:56.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:81:0)
[18:39:56.397] Timer tick, asking Buildbucket for the build status
[18:39:56.474] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:56.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:82:0) after 1m0s
[18:40:57.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:82:0)
[18:40:57.231] Timer tick, asking Buildbucket for the build status
[18:40:57.451] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:57.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:83:0) after 1m0s
[18:41:57.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:83:0)
[18:41:57.548] Timer tick, asking Buildbucket for the build status
[18:41:59.832] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:59.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:84:0) after 1m0s
[18:42:59.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:84:0)
[18:42:59.902] Timer tick, asking Buildbucket for the build status
[18:43:00.104] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:00.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:85:0) after 1m0s
[18:44:00.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:85:0)
[18:44:00.138] Timer tick, asking Buildbucket for the build status
[18:44:00.225] Build 8853670954942953056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:00.225] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9019101017151152496:86:0) after 1m0s
[18:44:12.145] Received PubSub notification, asking Buildbucket for the build status
[18:44:13.015] Build 8853670954942953056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:44:13.015] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1614883451451028",
"created_by": "project:emscripten-releases",
"created_ts": "1614878426566584",
"id": "8853670954942953056",
"parameters_json": "{\"builder_name\": \"mac\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@16214165f9c626eba2effc2d7c6b70470fe88084\", \"title\": \"16214165f9c626eba2effc2d7c6b70470fe88084\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/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\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@16214165f9c626eba2effc2d7c6b70470fe88084\", \"title\": \"16214165f9c626eba2effc2d7c6b70470fe88084\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084\"}]}, \"bot_id\": \"build498-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"mac\", \"got_revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"16214165f9c626eba2effc2d7c6b70470fe88084\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0d6fa4fff4995a408e468cf6236551b5d2129f3e939116f3b74cbba851b7eb7d_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"4\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-4578U\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:0a2e\"], \"hidpi\": [\"0\"], \"host_class\": [\"default\"], \"id\": [\"build498-m9\"], \"mac_model\": [\"Macmini7,1\"], \"machine_type\": [\"n1-standard-4\"], \"os\": [\"Mac\", \"Mac-10\", \"Mac-10.15\", \"Mac-10.15.6\", \"Mac-10.15.6-19G2021\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5725-1a0d85e\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614878791173152",
"status": "COMPLETED",
"status_changed_ts": "1614883451801062",
"tags": [
"builder:mac",
"buildset:commit/git/16214165f9c626eba2effc2d7c6b70470fe88084",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/16214165f9c626eba2effc2d7c6b70470fe88084",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019101017151152496",
"scheduler_job_id:emscripten-releases/mac",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8853670954942953056/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:521712d103bea010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614883451845717",
"url": "https://ci.chromium.org/b/8853670954942953056",
"utcnow_ts": "1614883452968680"
}
}
[18:44:13.015] Invocation finished in 1h23m47.93718749s with status SUCCEEDED