[22:25:24.506] New invocation is queued and will start shortly
[22:25:25.660] Starting the invocation (attempt 1)
[22:25:25.698] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:25:25.698] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:25:25.698] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9047619873246105296",
"parameters_json": "{\"builder_name\":\"linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1661d02cd48dd7b9aa8cabd40f5be7c59f283370\",\"title\":\"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9047619873246105296",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"gitiles_ref:refs/heads/master"
]
}
[22:25:26.549] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1587680725833474",
"id": "8882189811186674800",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"title\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587680726242104",
"tags": [
"builder:linux",
"buildset:commit/git/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9047619873246105296",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8882189811186674800/+/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": "1587680726242287",
"url": "https://ci.chromium.org/b/8882189811186674800",
"utcnow_ts": "1587680726537546"
}
}
[22:25:26.549] Task URL: https://ci.chromium.org/b/8882189811186674800
[22:25:26.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:2:0) after 1m0s
[22:26:26.642] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:2:0)
[22:26:26.642] Timer tick, asking Buildbucket for the build status
[22:26:26.992] Build 8882189811186674800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:26.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:3:0) after 1m0s
[22:26:31.635] Received PubSub notification, asking Buildbucket for the build status
[22:26:31.957] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:27.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:3:0)
[22:27:27.148] Timer tick, asking Buildbucket for the build status
[22:27:27.395] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:27.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:5:0) after 1m0s
[22:28:27.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:5:0)
[22:28:27.504] Timer tick, asking Buildbucket for the build status
[22:28:27.645] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:27.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:6:0) after 1m0s
[22:29:27.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:6:0)
[22:29:27.771] Timer tick, asking Buildbucket for the build status
[22:29:28.220] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:28.220] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:7:0) after 1m0s
[22:30:28.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:7:0)
[22:30:28.297] Timer tick, asking Buildbucket for the build status
[22:30:30.152] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:30.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:8:0) after 1m0s
[22:31:30.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:8:0)
[22:31:30.126] Timer tick, asking Buildbucket for the build status
[22:31:30.474] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:30.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:9:0) after 1m0s
[22:32:30.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:9:0)
[22:32:30.497] Timer tick, asking Buildbucket for the build status
[22:32:31.190] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:31.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:10:0) after 1m0s
[22:33:31.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:10:0)
[22:33:31.306] Timer tick, asking Buildbucket for the build status
[22:33:31.624] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:31.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:11:0) after 1m0s
[22:34:31.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:11:0)
[22:34:31.715] Timer tick, asking Buildbucket for the build status
[22:34:32.256] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:32.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:12:0) after 1m0s
[22:35:32.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:12:0)
[22:35:32.376] Timer tick, asking Buildbucket for the build status
[22:35:32.509] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:32.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:13:0) after 1m0s
[22:36:32.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:13:0)
[22:36:32.581] Timer tick, asking Buildbucket for the build status
[22:36:32.712] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:32.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:14:0) after 1m0s
[22:37:32.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:14:0)
[22:37:32.731] Timer tick, asking Buildbucket for the build status
[22:37:33.259] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:33.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:15:0) after 1m0s
[22:38:33.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:15:0)
[22:38:33.392] Timer tick, asking Buildbucket for the build status
[22:38:33.569] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:33.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:16:0) after 1m0s
[22:39:33.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:16:0)
[22:39:33.579] Timer tick, asking Buildbucket for the build status
[22:39:34.284] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:34.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:17:0) after 1m0s
[22:40:34.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:17:0)
[22:40:34.308] Timer tick, asking Buildbucket for the build status
[22:40:34.571] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:34.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:18:0) after 1m0s
[22:41:34.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:18:0)
[22:41:34.635] Timer tick, asking Buildbucket for the build status
[22:41:34.946] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:34.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:19:0) after 1m0s
[22:42:34.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:19:0)
[22:42:34.968] Timer tick, asking Buildbucket for the build status
[22:42:35.146] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:35.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:20:0) after 1m0s
[22:43:35.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:20:0)
[22:43:35.192] Timer tick, asking Buildbucket for the build status
[22:43:35.319] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:35.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:21:0) after 1m0s
[22:44:35.446] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:21:0)
[22:44:35.446] Timer tick, asking Buildbucket for the build status
[22:44:35.636] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:35.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:22:0) after 1m0s
[22:45:35.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:22:0)
[22:45:35.669] Timer tick, asking Buildbucket for the build status
[22:45:35.795] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:35.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:23:0) after 1m0s
[22:46:35.821] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:23:0)
[22:46:35.821] Timer tick, asking Buildbucket for the build status
[22:46:36.219] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:36.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:24:0) after 1m0s
[22:47:36.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:24:0)
[22:47:36.234] Timer tick, asking Buildbucket for the build status
[22:47:36.318] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:36.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:25:0) after 1m0s
[22:48:36.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:25:0)
[22:48:36.390] Timer tick, asking Buildbucket for the build status
[22:48:36.597] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:36.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:26:0) after 1m0s
[22:49:36.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:26:0)
[22:49:36.577] Timer tick, asking Buildbucket for the build status
[22:49:36.724] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:36.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:27:0) after 1m0s
[22:50:36.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:27:0)
[22:50:36.781] Timer tick, asking Buildbucket for the build status
[22:50:36.912] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:36.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:28:0) after 1m0s
[22:51:36.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:28:0)
[22:51:36.966] Timer tick, asking Buildbucket for the build status
[22:51:37.105] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:37.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:29:0) after 1m0s
[22:52:37.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:29:0)
[22:52:37.166] Timer tick, asking Buildbucket for the build status
[22:52:37.345] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:37.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:30:0) after 1m0s
[22:53:37.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:30:0)
[22:53:37.382] Timer tick, asking Buildbucket for the build status
[22:53:37.508] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:37.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:31:0) after 1m0s
[22:54:37.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:31:0)
[22:54:37.608] Timer tick, asking Buildbucket for the build status
[22:54:37.735] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:37.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:32:0) after 1m0s
[22:55:37.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:32:0)
[22:55:37.854] Timer tick, asking Buildbucket for the build status
[22:55:38.020] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:38.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:33:0) after 1m0s
[22:56:38.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:33:0)
[22:56:38.071] Timer tick, asking Buildbucket for the build status
[22:56:38.218] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:38.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:34:0) after 1m0s
[22:57:38.207] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:34:0)
[22:57:38.207] Timer tick, asking Buildbucket for the build status
[22:57:38.341] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:38.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:35:0) after 1m0s
[22:58:38.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:35:0)
[22:58:38.441] Timer tick, asking Buildbucket for the build status
[22:58:38.728] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:38.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:36:0) after 1m0s
[22:59:38.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:36:0)
[22:59:38.857] Timer tick, asking Buildbucket for the build status
[22:59:39.004] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:39.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:37:0) after 1m0s
[23:00:39.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:37:0)
[23:00:39.152] Timer tick, asking Buildbucket for the build status
[23:00:39.295] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:39.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:38:0) after 1m0s
[23:01:39.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:38:0)
[23:01:39.427] Timer tick, asking Buildbucket for the build status
[23:01:39.538] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:39.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:39:0) after 1m0s
[23:02:39.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:39:0)
[23:02:39.695] Timer tick, asking Buildbucket for the build status
[23:02:39.901] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:39.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:40:0) after 1m0s
[23:03:40.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:40:0)
[23:03:40.002] Timer tick, asking Buildbucket for the build status
[23:03:40.073] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:40.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:41:0) after 1m0s
[23:04:40.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:41:0)
[23:04:40.067] Timer tick, asking Buildbucket for the build status
[23:04:40.423] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:40.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:42:0) after 1m0s
[23:05:40.451] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:42:0)
[23:05:40.451] Timer tick, asking Buildbucket for the build status
[23:05:40.754] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:40.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:43:0) after 1m0s
[23:06:40.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:43:0)
[23:06:40.793] Timer tick, asking Buildbucket for the build status
[23:06:40.921] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:40.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:44:0) after 1m0s
[23:07:40.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:44:0)
[23:07:40.959] Timer tick, asking Buildbucket for the build status
[23:07:41.095] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:41.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:45:0) after 1m0s
[23:08:41.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:45:0)
[23:08:41.150] Timer tick, asking Buildbucket for the build status
[23:08:41.386] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:41.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:46:0) after 1m0s
[23:09:41.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:46:0)
[23:09:41.486] Timer tick, asking Buildbucket for the build status
[23:09:41.613] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:41.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:47:0) after 1m0s
[23:10:41.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:47:0)
[23:10:41.705] Timer tick, asking Buildbucket for the build status
[23:10:42.851] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:42.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:48:0) after 1m0s
[23:11:42.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:48:0)
[23:11:42.897] Timer tick, asking Buildbucket for the build status
[23:11:43.194] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:43.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:49:0) after 1m0s
[23:12:43.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:49:0)
[23:12:43.215] Timer tick, asking Buildbucket for the build status
[23:12:43.540] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:43.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:50:0) after 1m0s
[23:13:43.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:50:0)
[23:13:43.569] Timer tick, asking Buildbucket for the build status
[23:13:43.670] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:43.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:51:0) after 1m0s
[23:14:43.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:51:0)
[23:14:43.756] Timer tick, asking Buildbucket for the build status
[23:14:43.902] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:43.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:52:0) after 1m0s
[23:15:44.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:52:0)
[23:15:44.045] Timer tick, asking Buildbucket for the build status
[23:15:44.192] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:44.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:53:0) after 1m0s
[23:16:44.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:53:0)
[23:16:44.202] Timer tick, asking Buildbucket for the build status
[23:16:44.518] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:44.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:54:0) after 1m0s
[23:17:44.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:54:0)
[23:17:44.603] Timer tick, asking Buildbucket for the build status
[23:17:44.761] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:44.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:55:0) after 1m0s
[23:18:44.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:55:0)
[23:18:44.773] Timer tick, asking Buildbucket for the build status
[23:18:44.926] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:44.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:56:0) after 1m0s
[23:19:44.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:56:0)
[23:19:44.924] Timer tick, asking Buildbucket for the build status
[23:19:45.331] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:45.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:57:0) after 1m0s
[23:20:45.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:57:0)
[23:20:45.378] Timer tick, asking Buildbucket for the build status
[23:20:45.492] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:45.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:58:0) after 1m0s
[23:21:45.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:58:0)
[23:21:45.531] Timer tick, asking Buildbucket for the build status
[23:21:45.706] Build 8882189811186674800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:45.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9047619873246105296:59:0) after 1m0s
[23:22:12.268] Received PubSub notification, asking Buildbucket for the build status
[23:22:12.342] Build 8882189811186674800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:22:12.342] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1587684131581491",
"created_by": "project:emscripten-releases",
"created_ts": "1587680725833474",
"id": "8882189811186674800",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"title\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"title\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-h8bk\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1661d02cd48dd7b9aa8cabd40f5be7c59f283370\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_461cb3affc6f270af3ac9b28cfd8af8c60e3aebc1d1ae3ebd8f2aa2c00b55fae_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-0-h8bk\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5018-c7f912d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1587680790861116",
"status": "COMPLETED",
"status_changed_ts": "1587684131963037",
"tags": [
"builder:linux",
"buildset:commit/git/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1661d02cd48dd7b9aa8cabd40f5be7c59f283370",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9047619873246105296",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8882189811186674800/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4bc1f6f7c0b62c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1587684131972900",
"url": "https://ci.chromium.org/b/8882189811186674800",
"utcnow_ts": "1587684132328136"
}
}
[23:22:12.342] Invocation finished in 56m47.856510302s with status SUCCEEDED