[22:05:02.550] New invocation is queued and will start shortly
[22:05:04.071] Starting the invocation (attempt 1)
[22:05:04.123] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:05:04.123] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:05:04.123] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9072263529422311776",
"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\":\"071885a81cb39cdde28dfa0a37ed71a254711933\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@071885a81cb39cdde28dfa0a37ed71a254711933\",\"title\":\"071885a81cb39cdde28dfa0a37ed71a254711933\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"071885a81cb39cdde28dfa0a37ed71a254711933\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9072263529422311776",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/071885a81cb39cdde28dfa0a37ed71a254711933",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933",
"gitiles_ref:refs/heads/master"
]
}
[22:05:05.302] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1564178704313190",
"id": "8906833466904938896",
"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\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@071885a81cb39cdde28dfa0a37ed71a254711933\", \"title\": \"071885a81cb39cdde28dfa0a37ed71a254711933\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564178705026694",
"tags": [
"builder:linux",
"buildset:commit/git/071885a81cb39cdde28dfa0a37ed71a254711933",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072263529422311776",
"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/8906833466904938896/+/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": "1564178705026827",
"url": "https://ci.chromium.org/b/8906833466904938896",
"utcnow_ts": "1564178705292996"
}
}
[22:05:05.302] Task URL: https://ci.chromium.org/b/8906833466904938896
[22:05:05.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:2:0) after 1m0s
[22:06:05.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:2:0)
[22:06:05.432] Timer tick, asking Buildbucket for the build status
[22:06:06.128] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:06.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:3:0) after 1m0s
[22:07:06.144] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:3:0)
[22:07:06.144] Timer tick, asking Buildbucket for the build status
[22:07:06.502] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:06.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:4:0) after 1m0s
[22:08:06.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:4:0)
[22:08:06.551] Timer tick, asking Buildbucket for the build status
[22:08:07.085] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:07.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:5:0) after 1m0s
[22:09:07.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:5:0)
[22:09:07.168] Timer tick, asking Buildbucket for the build status
[22:09:08.386] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:08.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:6:0) after 1m0s
[22:10:08.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:6:0)
[22:10:08.578] Timer tick, asking Buildbucket for the build status
[22:10:08.825] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:08.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:7:0) after 1m0s
[22:11:08.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:7:0)
[22:11:08.885] Timer tick, asking Buildbucket for the build status
[22:11:09.490] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:09.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:8:0) after 1m0s
[22:12:09.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:8:0)
[22:12:09.524] Timer tick, asking Buildbucket for the build status
[22:12:10.503] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:10.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:9:0) after 1m0s
[22:13:10.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:9:0)
[22:13:10.562] Timer tick, asking Buildbucket for the build status
[22:13:11.488] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:11.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:10:0) after 1m0s
[22:14:11.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:10:0)
[22:14:11.516] Timer tick, asking Buildbucket for the build status
[22:14:11.909] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:11.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:11:0) after 1m0s
[22:15:11.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:11:0)
[22:15:11.990] Timer tick, asking Buildbucket for the build status
[22:15:13.619] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:13.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:12:0) after 1m0s
[22:16:13.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:12:0)
[22:16:13.638] Timer tick, asking Buildbucket for the build status
[22:16:14.491] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:14.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:13:0) after 1m0s
[22:17:14.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:13:0)
[22:17:14.515] Timer tick, asking Buildbucket for the build status
[22:17:14.697] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:14.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:14:0) after 1m0s
[22:18:14.722] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:14:0)
[22:18:14.722] Timer tick, asking Buildbucket for the build status
[22:18:17.734] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:17.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:15:0) after 1m0s
[22:19:17.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:15:0)
[22:19:17.734] Timer tick, asking Buildbucket for the build status
[22:19:19.004] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:19.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:16:0) after 1m0s
[22:20:19.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:16:0)
[22:20:19.024] Timer tick, asking Buildbucket for the build status
[22:20:19.317] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:19.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:17:0) after 1m0s
[22:21:19.365] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:17:0)
[22:21:19.365] Timer tick, asking Buildbucket for the build status
[22:21:20.166] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:20.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:18:0) after 1m0s
[22:22:20.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:18:0)
[22:22:20.316] Timer tick, asking Buildbucket for the build status
[22:22:20.484] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:20.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:19:0) after 1m0s
[22:23:20.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:19:0)
[22:23:20.807] Timer tick, asking Buildbucket for the build status
[22:23:21.114] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:21.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:20:0) after 1m0s
[22:24:21.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:20:0)
[22:24:21.461] Timer tick, asking Buildbucket for the build status
[22:24:22.047] Build 8906833466904938896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:22.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:21:0) after 1m0s
[22:25:13.429] Received PubSub notification, asking Buildbucket for the build status
[22:25:14.004] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:22.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:21:0)
[22:25:22.102] Timer tick, asking Buildbucket for the build status
[22:25:22.315] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:22.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:23:0) after 1m0s
[22:26:22.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:23:0)
[22:26:22.323] Timer tick, asking Buildbucket for the build status
[22:26:22.833] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:22.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:24:0) after 1m0s
[22:27:22.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:24:0)
[22:27:22.904] Timer tick, asking Buildbucket for the build status
[22:27:23.126] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:23.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:25:0) after 1m0s
[22:28:23.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:25:0)
[22:28:23.230] Timer tick, asking Buildbucket for the build status
[22:28:23.550] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:23.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:26:0) after 1m0s
[22:29:23.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:26:0)
[22:29:23.605] Timer tick, asking Buildbucket for the build status
[22:29:24.572] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:24.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:27:0) after 1m0s
[22:30:24.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:27:0)
[22:30:24.620] Timer tick, asking Buildbucket for the build status
[22:30:24.933] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:24.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:28:0) after 1m0s
[22:31:25.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:28:0)
[22:31:25.108] Timer tick, asking Buildbucket for the build status
[22:31:28.768] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:28.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:29:0) after 1m0s
[22:32:28.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:29:0)
[22:32:28.906] Timer tick, asking Buildbucket for the build status
[22:32:29.848] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:29.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:30:0) after 1m0s
[22:33:29.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:30:0)
[22:33:29.829] Timer tick, asking Buildbucket for the build status
[22:33:30.107] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:30.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:31:0) after 1m0s
[22:34:30.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:31:0)
[22:34:30.196] Timer tick, asking Buildbucket for the build status
[22:34:32.411] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:32.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:32:0) after 1m0s
[22:35:32.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:32:0)
[22:35:32.608] Timer tick, asking Buildbucket for the build status
[22:35:33.110] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:33.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:33:0) after 1m0s
[22:36:33.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:33:0)
[22:36:33.166] Timer tick, asking Buildbucket for the build status
[22:36:33.468] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:33.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:34:0) after 1m0s
[22:37:33.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:34:0)
[22:37:33.562] Timer tick, asking Buildbucket for the build status
[22:37:33.827] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:33.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:35:0) after 1m0s
[22:38:34.173] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:35:0)
[22:38:34.173] Timer tick, asking Buildbucket for the build status
[22:38:34.439] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:34.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:36:0) after 1m0s
[22:39:34.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:36:0)
[22:39:34.523] Timer tick, asking Buildbucket for the build status
[22:39:35.364] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:35.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:37:0) after 1m0s
[22:40:35.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:37:0)
[22:40:35.395] Timer tick, asking Buildbucket for the build status
[22:40:35.705] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:35.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:38:0) after 1m0s
[22:41:35.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:38:0)
[22:41:35.735] Timer tick, asking Buildbucket for the build status
[22:41:41.930] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:41.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:39:0) after 1m0s
[22:42:41.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:39:0)
[22:42:41.973] Timer tick, asking Buildbucket for the build status
[22:42:44.774] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:44.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:40:0) after 1m0s
[22:43:44.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:40:0)
[22:43:44.869] Timer tick, asking Buildbucket for the build status
[22:43:45.329] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:45.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:41:0) after 1m0s
[22:44:45.352] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:41:0)
[22:44:45.352] Timer tick, asking Buildbucket for the build status
[22:44:45.587] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:45.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:42:0) after 1m0s
[22:45:45.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:42:0)
[22:45:45.658] Timer tick, asking Buildbucket for the build status
[22:45:46.812] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:46.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:43:0) after 1m0s
[22:46:46.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:43:0)
[22:46:46.971] Timer tick, asking Buildbucket for the build status
[22:46:49.591] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:49.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:44:0) after 1m0s
[22:47:49.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:44:0)
[22:47:49.620] Timer tick, asking Buildbucket for the build status
[22:47:50.417] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:50.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:45:0) after 1m0s
[22:48:50.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:45:0)
[22:48:50.540] Timer tick, asking Buildbucket for the build status
[22:48:50.678] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:50.678] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:46:0) after 1m0s
[22:49:50.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:46:0)
[22:49:50.868] Timer tick, asking Buildbucket for the build status
[22:49:51.031] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:51.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:47:0) after 1m0s
[22:50:51.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:47:0)
[22:50:51.094] Timer tick, asking Buildbucket for the build status
[22:50:51.297] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:51.297] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:48:0) after 1m0s
[22:51:51.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:48:0)
[22:51:51.418] Timer tick, asking Buildbucket for the build status
[22:51:51.568] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:51.568] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:49:0) after 1m0s
[22:52:51.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:49:0)
[22:52:51.736] Timer tick, asking Buildbucket for the build status
[22:52:51.881] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:51.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:50:0) after 1m0s
[22:53:51.948] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:50:0)
[22:53:51.948] Timer tick, asking Buildbucket for the build status
[22:53:52.357] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:52.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:51:0) after 1m0s
[22:54:52.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:51:0)
[22:54:52.416] Timer tick, asking Buildbucket for the build status
[22:54:52.587] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:52.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:52:0) after 1m0s
[22:55:52.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:52:0)
[22:55:52.740] Timer tick, asking Buildbucket for the build status
[22:55:52.976] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:52.976] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:53:0) after 1m0s
[22:56:53.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:53:0)
[22:56:53.062] Timer tick, asking Buildbucket for the build status
[22:56:53.613] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:54:0) after 1m0s
[22:57:53.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:54:0)
[22:57:53.604] Timer tick, asking Buildbucket for the build status
[22:57:54.138] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:55:0) after 1m0s
[22:58:54.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:55:0)
[22:58:54.220] Timer tick, asking Buildbucket for the build status
[22:58:55.265] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:55.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:56:0) after 1m0s
[22:59:55.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:56:0)
[22:59:55.357] Timer tick, asking Buildbucket for the build status
[22:59:55.956] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:57:0) after 1m0s
[23:00:56.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:57:0)
[23:00:56.028] Timer tick, asking Buildbucket for the build status
[23:00:56.230] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:56.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:58:0) after 1m0s
[23:01:56.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:58:0)
[23:01:56.278] Timer tick, asking Buildbucket for the build status
[23:01:56.957] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:56.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:59:0) after 1m0s
[23:02:57.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:59:0)
[23:02:57.033] Timer tick, asking Buildbucket for the build status
[23:02:57.131] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:57.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:60:0) after 1m0s
[23:03:57.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:60:0)
[23:03:57.188] Timer tick, asking Buildbucket for the build status
[23:03:57.824] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:57.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:61:0) after 1m0s
[23:04:57.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:61:0)
[23:04:57.847] Timer tick, asking Buildbucket for the build status
[23:04:58.145] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:58.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:62:0) after 1m0s
[23:05:58.170] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:62:0)
[23:05:58.170] Timer tick, asking Buildbucket for the build status
[23:05:58.530] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:58.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:63:0) after 1m0s
[23:06:58.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:63:0)
[23:06:58.542] Timer tick, asking Buildbucket for the build status
[23:06:58.669] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:58.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:64:0) after 1m0s
[23:07:58.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:64:0)
[23:07:58.885] Timer tick, asking Buildbucket for the build status
[23:07:59.063] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:59.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:65:0) after 1m0s
[23:08:59.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:65:0)
[23:08:59.067] Timer tick, asking Buildbucket for the build status
[23:09:00.045] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:00.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:66:0) after 1m0s
[23:10:00.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:66:0)
[23:10:00.747] Timer tick, asking Buildbucket for the build status
[23:10:01.590] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:01.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:67:0) after 1m0s
[23:11:01.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:67:0)
[23:11:01.981] Timer tick, asking Buildbucket for the build status
[23:11:02.352] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:02.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:68:0) after 1m0s
[23:12:02.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:68:0)
[23:12:02.551] Timer tick, asking Buildbucket for the build status
[23:12:03.314] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:03.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:69:0) after 1m0s
[23:13:03.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:69:0)
[23:13:03.393] Timer tick, asking Buildbucket for the build status
[23:13:04.614] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:04.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:70:0) after 1m0s
[23:14:04.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:70:0)
[23:14:04.624] Timer tick, asking Buildbucket for the build status
[23:14:05.024] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:05.024] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:71:0) after 1m0s
[23:15:05.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:71:0)
[23:15:05.085] Timer tick, asking Buildbucket for the build status
[23:15:11.931] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:11.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:72:0) after 1m0s
[23:16:12.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:72:0)
[23:16:12.037] Timer tick, asking Buildbucket for the build status
[23:16:12.812] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:12.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:73:0) after 1m0s
[23:17:12.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:73:0)
[23:17:12.926] Timer tick, asking Buildbucket for the build status
[23:17:13.103] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:13.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:74:0) after 1m0s
[23:18:13.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:74:0)
[23:18:13.198] Timer tick, asking Buildbucket for the build status
[23:18:13.989] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:13.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:75:0) after 1m0s
[23:19:14.047] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:75:0)
[23:19:14.047] Timer tick, asking Buildbucket for the build status
[23:19:14.229] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:14.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:76:0) after 1m0s
[23:20:14.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:76:0)
[23:20:14.235] Timer tick, asking Buildbucket for the build status
[23:20:15.752] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:15.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:77:0) after 1m0s
[23:21:15.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:77:0)
[23:21:15.848] Timer tick, asking Buildbucket for the build status
[23:21:17.548] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:17.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:78:0) after 1m0s
[23:22:17.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:78:0)
[23:22:17.627] Timer tick, asking Buildbucket for the build status
[23:22:17.881] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:17.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:79:0) after 1m0s
[23:23:17.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:79:0)
[23:23:17.949] Timer tick, asking Buildbucket for the build status
[23:23:21.411] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:21.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:80:0) after 1m0s
[23:24:21.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:80:0)
[23:24:21.449] Timer tick, asking Buildbucket for the build status
[23:24:22.299] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:22.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:81:0) after 1m0s
[23:25:22.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:81:0)
[23:25:22.367] Timer tick, asking Buildbucket for the build status
[23:25:22.714] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:22.714] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:82:0) after 1m0s
[23:26:22.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:82:0)
[23:26:22.805] Timer tick, asking Buildbucket for the build status
[23:26:23.339] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:23.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:83:0) after 1m0s
[23:27:23.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:83:0)
[23:27:23.384] Timer tick, asking Buildbucket for the build status
[23:27:23.551] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:23.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:84:0) after 1m0s
[23:28:23.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:84:0)
[23:28:23.617] Timer tick, asking Buildbucket for the build status
[23:28:23.779] Build 8906833466904938896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:23.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072263529422311776:85:0) after 1m0s
[23:28:47.792] Received PubSub notification, asking Buildbucket for the build status
[23:28:47.854] Build 8906833466904938896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:28:47.854] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1564183726681626",
"created_by": "project:emscripten-releases",
"created_ts": "1564178704313190",
"id": "8906833466904938896",
"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\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@071885a81cb39cdde28dfa0a37ed71a254711933\", \"title\": \"071885a81cb39cdde28dfa0a37ed71a254711933\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}}",
"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\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@071885a81cb39cdde28dfa0a37ed71a254711933\", \"title\": \"071885a81cb39cdde28dfa0a37ed71a254711933\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-27lt\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"071885a81cb39cdde28dfa0a37ed71a254711933\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"071885a81cb39cdde28dfa0a37ed71a254711933\"}, \"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\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-0-27lt\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"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\": [\"4382-5929880\"], \"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": "1564179910626488",
"status": "COMPLETED",
"status_changed_ts": "1564183727094559",
"tags": [
"builder:linux",
"buildset:commit/git/071885a81cb39cdde28dfa0a37ed71a254711933",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/071885a81cb39cdde28dfa0a37ed71a254711933",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072263529422311776",
"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/8906833466904938896/+/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:46492276404d8e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1564183727139020",
"url": "https://ci.chromium.org/b/8906833466904938896",
"utcnow_ts": "1564183727845853"
}
}
[23:28:47.854] Invocation finished in 1h23m45.326138934s with status SUCCEEDED