[04:34:10.390] New invocation is queued and will start shortly
[04:34:11.613] Starting the invocation (attempt 1)
[04:34:11.661] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:34:11.661] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:34:11.662] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9034007127616939232",
"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\":\"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\",\"title\":\"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:mac",
"scheduler_invocation_id:9034007127616939232",
"scheduler_job_id:emscripten-releases/mac",
"user_agent:luci-scheduler",
"buildset:commit/git/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"gitiles_ref:refs/heads/master"
]
}
[04:34:12.296] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1600662851755006",
"id": "8868577065516221760",
"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\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"title\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600662852174524",
"tags": [
"builder:mac",
"buildset:commit/git/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034007127616939232",
"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/8868577065516221760/+/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": "1600662852175982",
"url": "https://ci.chromium.org/b/8868577065516221760",
"utcnow_ts": "1600662852282772"
}
}
[04:34:12.296] Task URL: https://ci.chromium.org/b/8868577065516221760
[04:34:12.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:2:0) after 1m0s
[04:34:38.964] Received PubSub notification, asking Buildbucket for the build status
[04:34:39.041] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:12.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:2:0)
[04:35:12.523] Timer tick, asking Buildbucket for the build status
[04:35:13.592] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:13.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:4:0) after 1m0s
[04:36:13.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:4:0)
[04:36:13.703] Timer tick, asking Buildbucket for the build status
[04:36:13.835] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:13.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:5:0) after 1m0s
[04:37:14.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:5:0)
[04:37:14.020] Timer tick, asking Buildbucket for the build status
[04:37:14.145] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:14.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:6:0) after 1m0s
[04:38:14.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:6:0)
[04:38:14.329] Timer tick, asking Buildbucket for the build status
[04:38:14.450] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:14.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:7:0) after 1m0s
[04:39:14.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:7:0)
[04:39:14.538] Timer tick, asking Buildbucket for the build status
[04:39:14.675] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:14.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:8:0) after 1m0s
[04:40:14.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:8:0)
[04:40:14.741] Timer tick, asking Buildbucket for the build status
[04:40:14.844] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:14.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:9:0) after 1m0s
[04:41:14.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:9:0)
[04:41:14.911] Timer tick, asking Buildbucket for the build status
[04:41:15.164] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:15.164] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:10:0) after 1m0s
[04:42:15.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:10:0)
[04:42:15.177] Timer tick, asking Buildbucket for the build status
[04:42:15.586] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:15.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:11:0) after 1m0s
[04:43:15.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:11:0)
[04:43:15.624] Timer tick, asking Buildbucket for the build status
[04:43:15.693] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:15.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:12:0) after 1m0s
[04:44:15.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:12:0)
[04:44:15.772] Timer tick, asking Buildbucket for the build status
[04:44:15.908] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:15.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:13:0) after 1m0s
[04:45:15.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:13:0)
[04:45:15.984] Timer tick, asking Buildbucket for the build status
[04:45:16.065] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:16.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:14:0) after 1m0s
[04:46:16.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:14:0)
[04:46:16.116] Timer tick, asking Buildbucket for the build status
[04:46:16.181] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:16.181] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:15:0) after 1m0s
[04:47:16.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:15:0)
[04:47:16.241] Timer tick, asking Buildbucket for the build status
[04:47:16.428] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:16.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:16:0) after 1m0s
[04:48:16.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:16:0)
[04:48:16.634] Timer tick, asking Buildbucket for the build status
[04:48:16.985] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:16.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:17:0) after 1m0s
[04:49:17.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:17:0)
[04:49:17.136] Timer tick, asking Buildbucket for the build status
[04:49:17.330] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:17.330] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:18:0) after 1m0s
[04:50:17.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:18:0)
[04:50:17.409] Timer tick, asking Buildbucket for the build status
[04:50:17.701] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:17.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:19:0) after 1m0s
[04:51:17.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:19:0)
[04:51:17.724] Timer tick, asking Buildbucket for the build status
[04:51:17.787] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:17.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:20:0) after 1m0s
[04:52:17.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:20:0)
[04:52:17.817] Timer tick, asking Buildbucket for the build status
[04:52:17.982] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:17.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:21:0) after 1m0s
[04:53:18.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:21:0)
[04:53:18.025] Timer tick, asking Buildbucket for the build status
[04:53:18.143] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:18.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:22:0) after 1m0s
[04:54:18.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:22:0)
[04:54:18.269] Timer tick, asking Buildbucket for the build status
[04:54:18.446] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:18.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:23:0) after 1m0s
[04:55:18.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:23:0)
[04:55:18.629] Timer tick, asking Buildbucket for the build status
[04:55:18.712] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:18.712] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:24:0) after 1m0s
[04:56:19.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:24:0)
[04:56:19.002] Timer tick, asking Buildbucket for the build status
[04:56:19.093] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:19.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:25:0) after 1m0s
[04:57:19.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:25:0)
[04:57:19.153] Timer tick, asking Buildbucket for the build status
[04:57:19.222] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:19.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:26:0) after 1m0s
[04:58:19.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:26:0)
[04:58:19.362] Timer tick, asking Buildbucket for the build status
[04:58:19.443] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:19.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:27:0) after 1m0s
[04:59:19.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:27:0)
[04:59:19.503] Timer tick, asking Buildbucket for the build status
[04:59:19.844] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:19.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:28:0) after 1m0s
[05:00:19.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:28:0)
[05:00:19.917] Timer tick, asking Buildbucket for the build status
[05:00:19.997] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:19.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:29:0) after 1m0s
[05:01:20.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:29:0)
[05:01:20.064] Timer tick, asking Buildbucket for the build status
[05:01:20.196] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:20.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:30:0) after 1m0s
[05:02:20.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:30:0)
[05:02:20.225] Timer tick, asking Buildbucket for the build status
[05:02:20.595] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:20.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:31:0) after 1m0s
[05:03:20.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:31:0)
[05:03:20.734] Timer tick, asking Buildbucket for the build status
[05:03:20.867] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:20.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:32:0) after 1m0s
[05:04:21.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:32:0)
[05:04:21.188] Timer tick, asking Buildbucket for the build status
[05:04:21.355] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:21.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:33:0) after 1m0s
[05:05:21.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:33:0)
[05:05:21.343] Timer tick, asking Buildbucket for the build status
[05:05:21.572] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:21.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:34:0) after 1m0s
[05:06:21.711] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:34:0)
[05:06:21.711] Timer tick, asking Buildbucket for the build status
[05:06:21.775] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:21.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:35:0) after 1m0s
[05:07:21.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:35:0)
[05:07:21.848] Timer tick, asking Buildbucket for the build status
[05:07:21.949] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:21.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:36:0) after 1m0s
[05:08:21.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:36:0)
[05:08:21.942] Timer tick, asking Buildbucket for the build status
[05:08:22.027] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:22.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:37:0) after 1m0s
[05:09:22.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:37:0)
[05:09:22.115] Timer tick, asking Buildbucket for the build status
[05:09:22.345] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:22.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:38:0) after 1m0s
[05:10:22.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:38:0)
[05:10:22.384] Timer tick, asking Buildbucket for the build status
[05:10:22.488] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:22.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:39:0) after 1m0s
[05:11:22.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:39:0)
[05:11:22.567] Timer tick, asking Buildbucket for the build status
[05:11:22.789] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:22.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:40:0) after 1m0s
[05:12:22.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:40:0)
[05:12:22.820] Timer tick, asking Buildbucket for the build status
[05:12:22.923] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:22.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:41:0) after 1m0s
[05:13:23.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:41:0)
[05:13:23.008] Timer tick, asking Buildbucket for the build status
[05:13:23.078] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:23.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:42:0) after 1m0s
[05:14:23.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:42:0)
[05:14:23.169] Timer tick, asking Buildbucket for the build status
[05:14:23.685] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:23.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:43:0) after 1m0s
[05:15:23.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:43:0)
[05:15:23.706] Timer tick, asking Buildbucket for the build status
[05:15:23.864] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:23.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:44:0) after 1m0s
[05:16:23.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:44:0)
[05:16:23.937] Timer tick, asking Buildbucket for the build status
[05:16:24.008] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:24.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:45:0) after 1m0s
[05:17:24.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:45:0)
[05:17:24.031] Timer tick, asking Buildbucket for the build status
[05:17:24.128] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:24.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:46:0) after 1m0s
[05:18:24.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:46:0)
[05:18:24.166] Timer tick, asking Buildbucket for the build status
[05:18:24.292] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:24.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:47:0) after 1m0s
[05:19:24.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:47:0)
[05:19:24.323] Timer tick, asking Buildbucket for the build status
[05:19:24.524] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:24.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:48:0) after 1m0s
[05:20:24.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:48:0)
[05:20:24.575] Timer tick, asking Buildbucket for the build status
[05:20:24.665] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:24.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:49:0) after 1m0s
[05:21:24.711] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:49:0)
[05:21:24.711] Timer tick, asking Buildbucket for the build status
[05:21:24.870] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:24.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:50:0) after 1m0s
[05:22:24.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:50:0)
[05:22:24.898] Timer tick, asking Buildbucket for the build status
[05:22:25.027] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:25.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:51:0) after 1m0s
[05:23:25.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:51:0)
[05:23:25.051] Timer tick, asking Buildbucket for the build status
[05:23:25.129] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:25.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:52:0) after 1m0s
[05:24:25.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:52:0)
[05:24:25.146] Timer tick, asking Buildbucket for the build status
[05:24:25.628] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:25.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:53:0) after 1m0s
[05:25:25.647] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:53:0)
[05:25:25.647] Timer tick, asking Buildbucket for the build status
[05:25:25.906] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:25.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:54:0) after 1m0s
[05:26:25.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:54:0)
[05:26:25.932] Timer tick, asking Buildbucket for the build status
[05:26:26.095] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:26.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:55:0) after 1m0s
[05:27:26.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:55:0)
[05:27:26.121] Timer tick, asking Buildbucket for the build status
[05:27:26.344] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:26.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:56:0) after 1m0s
[05:28:26.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:56:0)
[05:28:26.391] Timer tick, asking Buildbucket for the build status
[05:28:26.466] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:26.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:57:0) after 1m0s
[05:29:26.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:57:0)
[05:29:26.495] Timer tick, asking Buildbucket for the build status
[05:29:26.625] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:26.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:58:0) after 1m0s
[05:30:26.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:58:0)
[05:30:26.758] Timer tick, asking Buildbucket for the build status
[05:30:26.871] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:26.871] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:59:0) after 1m0s
[05:31:26.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:59:0)
[05:31:26.906] Timer tick, asking Buildbucket for the build status
[05:31:26.999] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:26.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:60:0) after 1m0s
[05:32:27.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:60:0)
[05:32:27.055] Timer tick, asking Buildbucket for the build status
[05:32:27.417] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:27.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:61:0) after 1m0s
[05:33:27.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:61:0)
[05:33:27.438] Timer tick, asking Buildbucket for the build status
[05:33:27.499] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:27.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:62:0) after 1m0s
[05:34:27.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:62:0)
[05:34:27.533] Timer tick, asking Buildbucket for the build status
[05:34:27.641] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:27.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:63:0) after 1m0s
[05:35:27.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:63:0)
[05:35:27.673] Timer tick, asking Buildbucket for the build status
[05:35:27.744] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:27.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:64:0) after 1m0s
[05:36:27.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:64:0)
[05:36:27.766] Timer tick, asking Buildbucket for the build status
[05:36:28.034] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:28.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:65:0) after 1m0s
[05:37:28.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:65:0)
[05:37:28.025] Timer tick, asking Buildbucket for the build status
[05:37:28.093] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:28.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:66:0) after 1m0s
[05:38:28.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:66:0)
[05:38:28.164] Timer tick, asking Buildbucket for the build status
[05:38:28.256] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:28.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:67:0) after 1m0s
[05:39:28.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:67:0)
[05:39:28.289] Timer tick, asking Buildbucket for the build status
[05:39:28.491] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:28.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:68:0) after 1m0s
[05:40:28.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:68:0)
[05:40:28.546] Timer tick, asking Buildbucket for the build status
[05:40:28.627] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:28.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:69:0) after 1m0s
[05:41:28.754] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:69:0)
[05:41:28.754] Timer tick, asking Buildbucket for the build status
[05:41:28.830] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:28.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:70:0) after 1m0s
[05:42:28.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:70:0)
[05:42:28.856] Timer tick, asking Buildbucket for the build status
[05:42:28.934] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:28.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:71:0) after 1m0s
[05:43:28.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:71:0)
[05:43:28.975] Timer tick, asking Buildbucket for the build status
[05:43:29.216] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:29.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:72:0) after 1m0s
[05:44:29.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:72:0)
[05:44:29.292] Timer tick, asking Buildbucket for the build status
[05:44:29.497] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:29.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:73:0) after 1m0s
[05:45:29.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:73:0)
[05:45:29.522] Timer tick, asking Buildbucket for the build status
[05:45:29.587] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:29.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:74:0) after 1m0s
[05:46:29.613] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:74:0)
[05:46:29.613] Timer tick, asking Buildbucket for the build status
[05:46:29.909] Build 8868577065516221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:29.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9034007127616939232:75:0) after 1m0s
[05:46:43.622] Received PubSub notification, asking Buildbucket for the build status
[05:46:43.690] Build 8868577065516221760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:46:43.690] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1600667202328548",
"created_by": "project:emscripten-releases",
"created_ts": "1600662851755006",
"id": "8868577065516221760",
"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\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"title\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/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\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"title\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}]}, \"bot_id\": \"build498-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"mac\", \"got_revision\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5787bb9fba7b7599a664c89f6a77c1d85dcb91c3\"}, \"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.13\", \"Mac-10.13.6\", \"Mac-10.13.6-17G65\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"5531-8ab19d9\"], \"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": "1600662878067669",
"status": "COMPLETED",
"status_changed_ts": "1600667203079650",
"tags": [
"builder:mac",
"buildset:commit/git/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5787bb9fba7b7599a664c89f6a77c1d85dcb91c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034007127616939232",
"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/8868577065516221760/+/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:4ec7c25bfff77210",
"user_agent:luci-scheduler"
],
"updated_ts": "1600667203110763",
"url": "https://ci.chromium.org/b/8868577065516221760",
"utcnow_ts": "1600667203681492"
}
}
[05:46:43.690] Invocation finished in 1h12m33.32179161s with status SUCCEEDED