[10:50:38.692] New invocation is queued and will start shortly
[10:50:39.876] Starting the invocation (attempt 1)
[10:50:39.924] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:50:39.924] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:50:39.924] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9071852974023415616",
"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\":\"777e60ae8d13f671fba05020ae24dec9020ae331\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@777e60ae8d13f671fba05020ae24dec9020ae331\",\"title\":\"777e60ae8d13f671fba05020ae24dec9020ae331\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"777e60ae8d13f671fba05020ae24dec9020ae331\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9071852974023415616",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/777e60ae8d13f671fba05020ae24dec9020ae331",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331",
"gitiles_ref:refs/heads/master"
]
}
[10:50:40.555] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1564570240019854",
"id": "8906422911960360048",
"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\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@777e60ae8d13f671fba05020ae24dec9020ae331\", \"title\": \"777e60ae8d13f671fba05020ae24dec9020ae331\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564570240371660",
"tags": [
"builder:linux",
"buildset:commit/git/777e60ae8d13f671fba05020ae24dec9020ae331",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071852974023415616",
"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/8906422911960360048/+/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": "1564570240371751",
"url": "https://ci.chromium.org/b/8906422911960360048",
"utcnow_ts": "1564570240539732"
}
}
[10:50:40.555] Task URL: https://ci.chromium.org/b/8906422911960360048
[10:50:40.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:2:0) after 1m0s
[10:51:40.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:2:0)
[10:51:40.622] Timer tick, asking Buildbucket for the build status
[10:51:40.710] Build 8906422911960360048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:40.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:3:0) after 1m0s
[10:51:42.191] Received PubSub notification, asking Buildbucket for the build status
[10:51:42.302] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:40.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:3:0)
[10:52:40.916] Timer tick, asking Buildbucket for the build status
[10:52:41.155] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:41.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:5:0) after 1m0s
[10:53:41.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:5:0)
[10:53:41.370] Timer tick, asking Buildbucket for the build status
[10:53:41.470] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:41.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:6:0) after 1m0s
[10:54:41.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:6:0)
[10:54:41.593] Timer tick, asking Buildbucket for the build status
[10:54:41.829] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:41.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:7:0) after 1m0s
[10:55:41.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:7:0)
[10:55:41.887] Timer tick, asking Buildbucket for the build status
[10:55:41.983] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:41.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:8:0) after 1m0s
[10:56:42.084] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:8:0)
[10:56:42.084] Timer tick, asking Buildbucket for the build status
[10:56:42.348] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:42.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:9:0) after 1m0s
[10:57:42.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:9:0)
[10:57:42.411] Timer tick, asking Buildbucket for the build status
[10:57:42.600] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:42.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:10:0) after 1m0s
[10:58:42.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:10:0)
[10:58:42.825] Timer tick, asking Buildbucket for the build status
[10:58:43.098] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:43.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:11:0) after 1m0s
[10:59:43.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:11:0)
[10:59:43.117] Timer tick, asking Buildbucket for the build status
[10:59:43.434] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:43.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:12:0) after 1m0s
[11:00:43.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:12:0)
[11:00:43.544] Timer tick, asking Buildbucket for the build status
[11:00:44.330] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:44.330] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:13:0) after 1m0s
[11:01:44.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:13:0)
[11:01:44.566] Timer tick, asking Buildbucket for the build status
[11:01:44.741] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:44.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:14:0) after 1m0s
[11:02:45.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:14:0)
[11:02:45.137] Timer tick, asking Buildbucket for the build status
[11:02:46.310] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:46.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:15:0) after 1m0s
[11:03:46.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:15:0)
[11:03:46.794] Timer tick, asking Buildbucket for the build status
[11:03:46.905] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:46.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:16:0) after 1m0s
[11:04:47.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:16:0)
[11:04:47.125] Timer tick, asking Buildbucket for the build status
[11:04:47.440] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:47.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:17:0) after 1m0s
[11:05:47.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:17:0)
[11:05:47.659] Timer tick, asking Buildbucket for the build status
[11:05:47.892] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:47.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:18:0) after 1m0s
[11:06:47.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:18:0)
[11:06:47.911] Timer tick, asking Buildbucket for the build status
[11:06:48.972] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:48.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:19:0) after 1m0s
[11:07:49.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:19:0)
[11:07:49.285] Timer tick, asking Buildbucket for the build status
[11:07:50.980] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:50.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:20:0) after 1m0s
[11:08:51.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:20:0)
[11:08:51.116] Timer tick, asking Buildbucket for the build status
[11:08:51.661] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:51.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:21:0) after 1m0s
[11:09:51.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:21:0)
[11:09:51.766] Timer tick, asking Buildbucket for the build status
[11:09:52.029] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:52.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:22:0) after 1m0s
[11:10:52.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:22:0)
[11:10:52.021] Timer tick, asking Buildbucket for the build status
[11:10:52.175] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:52.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:23:0) after 1m0s
[11:11:52.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:23:0)
[11:11:52.260] Timer tick, asking Buildbucket for the build status
[11:11:52.363] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:52.363] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:24:0) after 1m0s
[11:12:52.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:24:0)
[11:12:52.367] Timer tick, asking Buildbucket for the build status
[11:12:52.478] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:52.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:25:0) after 1m0s
[11:13:52.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:25:0)
[11:13:52.590] Timer tick, asking Buildbucket for the build status
[11:13:52.901] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:52.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:26:0) after 1m0s
[11:14:52.943] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:26:0)
[11:14:52.943] Timer tick, asking Buildbucket for the build status
[11:14:53.192] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:53.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:27:0) after 1m0s
[11:15:53.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:27:0)
[11:15:53.302] Timer tick, asking Buildbucket for the build status
[11:15:54.036] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:54.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:28:0) after 1m0s
[11:16:54.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:28:0)
[11:16:54.083] Timer tick, asking Buildbucket for the build status
[11:16:54.344] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:54.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:29:0) after 1m0s
[11:17:54.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:29:0)
[11:17:54.431] Timer tick, asking Buildbucket for the build status
[11:17:54.601] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:54.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:30:0) after 1m0s
[11:18:54.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:30:0)
[11:18:54.656] Timer tick, asking Buildbucket for the build status
[11:18:54.791] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:54.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:31:0) after 1m0s
[11:19:54.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:31:0)
[11:19:54.820] Timer tick, asking Buildbucket for the build status
[11:19:54.916] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:54.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:32:0) after 1m0s
[11:20:54.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:32:0)
[11:20:54.997] Timer tick, asking Buildbucket for the build status
[11:20:56.628] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:56.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:33:0) after 1m0s
[11:21:56.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:33:0)
[11:21:56.695] Timer tick, asking Buildbucket for the build status
[11:21:56.818] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:56.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:34:0) after 1m0s
[11:22:56.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:34:0)
[11:22:56.880] Timer tick, asking Buildbucket for the build status
[11:22:57.246] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:57.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:35:0) after 1m0s
[11:23:57.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:35:0)
[11:23:57.293] Timer tick, asking Buildbucket for the build status
[11:23:57.452] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:57.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:36:0) after 1m0s
[11:24:57.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:36:0)
[11:24:57.486] Timer tick, asking Buildbucket for the build status
[11:24:57.626] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:57.626] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:37:0) after 1m0s
[11:25:57.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:37:0)
[11:25:57.695] Timer tick, asking Buildbucket for the build status
[11:25:57.982] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:57.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:38:0) after 1m0s
[11:26:58.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:38:0)
[11:26:58.003] Timer tick, asking Buildbucket for the build status
[11:26:58.291] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:58.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:39:0) after 1m0s
[11:27:58.711] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:39:0)
[11:27:58.711] Timer tick, asking Buildbucket for the build status
[11:27:58.806] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:58.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:40:0) after 1m0s
[11:28:58.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:40:0)
[11:28:58.961] Timer tick, asking Buildbucket for the build status
[11:28:59.063] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:59.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:41:0) after 1m0s
[11:29:59.296] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:41:0)
[11:29:59.297] Timer tick, asking Buildbucket for the build status
[11:29:59.372] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:59.372] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:42:0) after 1m0s
[11:30:59.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:42:0)
[11:30:59.397] Timer tick, asking Buildbucket for the build status
[11:30:59.550] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:59.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:43:0) after 1m0s
[11:31:59.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:43:0)
[11:31:59.576] Timer tick, asking Buildbucket for the build status
[11:32:00.327] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:00.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:44:0) after 1m0s
[11:33:00.360] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:44:0)
[11:33:00.360] Timer tick, asking Buildbucket for the build status
[11:33:00.783] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:00.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:45:0) after 1m0s
[11:34:00.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:45:0)
[11:34:00.824] Timer tick, asking Buildbucket for the build status
[11:34:02.392] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:02.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:46:0) after 1m0s
[11:35:02.446] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:46:0)
[11:35:02.446] Timer tick, asking Buildbucket for the build status
[11:35:03.013] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:03.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:47:0) after 1m0s
[11:36:03.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:47:0)
[11:36:03.046] Timer tick, asking Buildbucket for the build status
[11:36:03.394] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:03.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:48:0) after 1m0s
[11:37:03.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:48:0)
[11:37:03.420] Timer tick, asking Buildbucket for the build status
[11:37:03.716] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:03.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:49:0) after 1m0s
[11:38:03.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:49:0)
[11:38:03.736] Timer tick, asking Buildbucket for the build status
[11:38:03.834] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:03.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:50:0) after 1m0s
[11:39:03.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:50:0)
[11:39:03.862] Timer tick, asking Buildbucket for the build status
[11:39:04.023] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:04.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:51:0) after 1m0s
[11:40:04.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:51:0)
[11:40:04.056] Timer tick, asking Buildbucket for the build status
[11:40:04.188] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:04.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:52:0) after 1m0s
[11:41:04.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:52:0)
[11:41:04.211] Timer tick, asking Buildbucket for the build status
[11:41:04.396] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:04.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:53:0) after 1m0s
[11:42:04.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:53:0)
[11:42:04.443] Timer tick, asking Buildbucket for the build status
[11:42:04.547] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:04.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:54:0) after 1m0s
[11:43:04.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:54:0)
[11:43:04.610] Timer tick, asking Buildbucket for the build status
[11:43:04.846] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:04.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:55:0) after 1m0s
[11:44:04.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:55:0)
[11:44:04.888] Timer tick, asking Buildbucket for the build status
[11:44:04.982] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:04.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:56:0) after 1m0s
[11:45:05.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:56:0)
[11:45:05.109] Timer tick, asking Buildbucket for the build status
[11:45:05.271] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:05.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:57:0) after 1m0s
[11:46:05.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:57:0)
[11:46:05.534] Timer tick, asking Buildbucket for the build status
[11:46:05.731] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:05.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:58:0) after 1m0s
[11:47:05.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:58:0)
[11:47:05.803] Timer tick, asking Buildbucket for the build status
[11:47:06.253] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:06.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:59:0) after 1m0s
[11:48:06.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:59:0)
[11:48:06.337] Timer tick, asking Buildbucket for the build status
[11:48:06.445] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:06.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:60:0) after 1m0s
[11:49:06.494] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:60:0)
[11:49:06.494] Timer tick, asking Buildbucket for the build status
[11:49:06.770] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:06.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:61:0) after 1m0s
[11:50:06.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:61:0)
[11:50:06.846] Timer tick, asking Buildbucket for the build status
[11:50:07.310] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:07.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:62:0) after 1m0s
[11:51:07.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:62:0)
[11:51:07.416] Timer tick, asking Buildbucket for the build status
[11:51:07.517] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:07.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:63:0) after 1m0s
[11:52:07.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:63:0)
[11:52:07.664] Timer tick, asking Buildbucket for the build status
[11:52:08.028] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:08.028] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:64:0) after 1m0s
[11:53:08.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:64:0)
[11:53:08.111] Timer tick, asking Buildbucket for the build status
[11:53:08.337] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:08.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:65:0) after 1m0s
[11:54:08.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:65:0)
[11:54:08.606] Timer tick, asking Buildbucket for the build status
[11:54:09.060] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:09.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:66:0) after 1m0s
[11:55:09.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:66:0)
[11:55:09.143] Timer tick, asking Buildbucket for the build status
[11:55:09.410] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:09.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:67:0) after 1m0s
[11:56:09.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:67:0)
[11:56:09.674] Timer tick, asking Buildbucket for the build status
[11:56:10.025] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:10.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:68:0) after 1m0s
[11:57:10.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:68:0)
[11:57:10.003] Timer tick, asking Buildbucket for the build status
[11:57:10.207] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:10.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:69:0) after 1m0s
[11:58:10.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:69:0)
[11:58:10.284] Timer tick, asking Buildbucket for the build status
[11:58:11.172] Build 8906422911960360048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:11.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9071852974023415616:70:0) after 1m0s
[11:58:44.304] Received PubSub notification, asking Buildbucket for the build status
[11:58:44.765] Build 8906422911960360048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:58:44.765] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1564574323208086",
"created_by": "project:emscripten-releases",
"created_ts": "1564570240019854",
"id": "8906422911960360048",
"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\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@777e60ae8d13f671fba05020ae24dec9020ae331\", \"title\": \"777e60ae8d13f671fba05020ae24dec9020ae331\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}}",
"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\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@777e60ae8d13f671fba05020ae24dec9020ae331\", \"title\": \"777e60ae8d13f671fba05020ae24dec9020ae331\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-3mmg\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"777e60ae8d13f671fba05020ae24dec9020ae331\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"777e60ae8d13f671fba05020ae24dec9020ae331\"}, \"swarming\": {\"bot_dimensions\": {\"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-3mmg\"], \"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": "1564570285396141",
"status": "COMPLETED",
"status_changed_ts": "1564574323908334",
"tags": [
"builder:linux",
"buildset:commit/git/777e60ae8d13f671fba05020ae24dec9020ae331",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/777e60ae8d13f671fba05020ae24dec9020ae331",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071852974023415616",
"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/8906422911960360048/+/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:466078d01f4af210",
"user_agent:luci-scheduler"
],
"updated_ts": "1564574324023009",
"url": "https://ci.chromium.org/b/8906422911960360048",
"utcnow_ts": "1564574324721762"
}
}
[11:58:44.765] Invocation finished in 1h8m6.097996098s with status SUCCEEDED