[08:01:28.621] New invocation is queued and will start shortly
[08:01:29.902] Starting the invocation (attempt 1)
[08:01:29.935] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:01:29.935] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:01:29.935] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9060357802409979648",
"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\":\"d39997e6d2903d67b5f61338663f3cd8531e0201\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d39997e6d2903d67b5f61338663f3cd8531e0201\",\"title\":\"d39997e6d2903d67b5f61338663f3cd8531e0201\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"d39997e6d2903d67b5f61338663f3cd8531e0201\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9060357802409979648",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/d39997e6d2903d67b5f61338663f3cd8531e0201",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201",
"gitiles_ref:refs/heads/master"
]
}
[08:01:30.745] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1575532890063470",
"id": "8894927740227659920",
"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\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d39997e6d2903d67b5f61338663f3cd8531e0201\", \"title\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575532890481144",
"tags": [
"builder:linux",
"buildset:commit/git/d39997e6d2903d67b5f61338663f3cd8531e0201",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060357802409979648",
"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/8894927740227659920/+/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": "1575532890481332",
"url": "https://ci.chromium.org/b/8894927740227659920",
"utcnow_ts": "1575532890732751"
}
}
[08:01:30.745] Task URL: https://ci.chromium.org/b/8894927740227659920
[08:01:30.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:2:0) after 1m0s
[08:01:42.244] Received PubSub notification, asking Buildbucket for the build status
[08:01:42.452] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:30.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:2:0)
[08:02:30.982] Timer tick, asking Buildbucket for the build status
[08:02:31.174] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:31.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:4:0) after 1m0s
[08:03:31.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:4:0)
[08:03:31.222] Timer tick, asking Buildbucket for the build status
[08:03:31.364] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:31.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:5:0) after 1m0s
[08:04:31.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:5:0)
[08:04:31.483] Timer tick, asking Buildbucket for the build status
[08:04:31.762] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:31.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:6:0) after 1m0s
[08:05:31.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:6:0)
[08:05:31.824] Timer tick, asking Buildbucket for the build status
[08:05:32.215] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:32.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:7:0) after 1m0s
[08:06:32.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:7:0)
[08:06:32.313] Timer tick, asking Buildbucket for the build status
[08:06:32.850] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:32.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:8:0) after 1m0s
[08:07:32.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:8:0)
[08:07:32.916] Timer tick, asking Buildbucket for the build status
[08:07:33.074] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:33.074] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:9:0) after 1m0s
[08:08:33.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:9:0)
[08:08:33.204] Timer tick, asking Buildbucket for the build status
[08:08:33.404] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:33.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:10:0) after 1m0s
[08:09:33.455] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:10:0)
[08:09:33.455] Timer tick, asking Buildbucket for the build status
[08:09:33.902] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:33.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:11:0) after 1m0s
[08:10:34.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:11:0)
[08:10:34.028] Timer tick, asking Buildbucket for the build status
[08:10:34.547] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:34.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:12:0) after 1m0s
[08:11:34.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:12:0)
[08:11:34.586] Timer tick, asking Buildbucket for the build status
[08:11:34.731] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:34.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:13:0) after 1m0s
[08:12:34.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:13:0)
[08:12:34.815] Timer tick, asking Buildbucket for the build status
[08:12:35.006] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:35.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:14:0) after 1m0s
[08:13:35.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:14:0)
[08:13:35.097] Timer tick, asking Buildbucket for the build status
[08:13:35.399] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:35.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:15:0) after 1m0s
[08:14:35.455] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:15:0)
[08:14:35.455] Timer tick, asking Buildbucket for the build status
[08:14:35.587] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:35.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:16:0) after 1m0s
[08:15:35.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:16:0)
[08:15:35.660] Timer tick, asking Buildbucket for the build status
[08:15:35.808] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:35.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:17:0) after 1m0s
[08:16:35.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:17:0)
[08:16:35.921] Timer tick, asking Buildbucket for the build status
[08:16:36.100] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:36.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:18:0) after 1m0s
[08:17:36.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:18:0)
[08:17:36.079] Timer tick, asking Buildbucket for the build status
[08:17:36.233] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:36.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:19:0) after 1m0s
[08:18:36.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:19:0)
[08:18:36.286] Timer tick, asking Buildbucket for the build status
[08:18:36.422] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:36.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:20:0) after 1m0s
[08:19:36.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:20:0)
[08:19:36.461] Timer tick, asking Buildbucket for the build status
[08:19:36.651] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:36.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:21:0) after 1m0s
[08:20:36.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:21:0)
[08:20:36.661] Timer tick, asking Buildbucket for the build status
[08:20:36.782] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:36.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:22:0) after 1m0s
[08:21:36.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:22:0)
[08:21:36.854] Timer tick, asking Buildbucket for the build status
[08:21:37.330] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:37.330] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:23:0) after 1m0s
[08:22:37.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:23:0)
[08:22:37.371] Timer tick, asking Buildbucket for the build status
[08:22:37.562] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:37.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:24:0) after 1m0s
[08:23:37.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:24:0)
[08:23:37.688] Timer tick, asking Buildbucket for the build status
[08:23:38.133] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:38.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:25:0) after 1m0s
[08:24:38.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:25:0)
[08:24:38.113] Timer tick, asking Buildbucket for the build status
[08:24:38.693] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:38.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:26:0) after 1m0s
[08:25:38.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:26:0)
[08:25:38.714] Timer tick, asking Buildbucket for the build status
[08:25:38.906] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:38.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:27:0) after 1m0s
[08:26:38.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:27:0)
[08:26:38.933] Timer tick, asking Buildbucket for the build status
[08:26:39.426] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:39.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:28:0) after 1m0s
[08:27:39.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:28:0)
[08:27:39.425] Timer tick, asking Buildbucket for the build status
[08:27:39.544] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:39.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:29:0) after 1m0s
[08:28:39.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:29:0)
[08:28:39.599] Timer tick, asking Buildbucket for the build status
[08:28:39.750] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:39.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:30:0) after 1m0s
[08:29:39.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:30:0)
[08:29:39.756] Timer tick, asking Buildbucket for the build status
[08:29:39.900] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:39.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:31:0) after 1m0s
[08:30:39.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:31:0)
[08:30:39.924] Timer tick, asking Buildbucket for the build status
[08:30:40.572] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:40.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:32:0) after 1m0s
[08:31:40.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:32:0)
[08:31:40.629] Timer tick, asking Buildbucket for the build status
[08:31:40.790] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:40.790] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:33:0) after 1m0s
[08:32:40.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:33:0)
[08:32:40.844] Timer tick, asking Buildbucket for the build status
[08:32:41.068] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:41.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:34:0) after 1m0s
[08:33:41.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:34:0)
[08:33:41.139] Timer tick, asking Buildbucket for the build status
[08:33:41.451] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:41.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:35:0) after 1m0s
[08:34:41.476] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:35:0)
[08:34:41.476] Timer tick, asking Buildbucket for the build status
[08:34:41.658] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:41.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:36:0) after 1m0s
[08:35:41.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:36:0)
[08:35:41.707] Timer tick, asking Buildbucket for the build status
[08:35:41.862] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:41.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:37:0) after 1m0s
[08:36:41.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:37:0)
[08:36:41.897] Timer tick, asking Buildbucket for the build status
[08:36:42.107] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:42.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:38:0) after 1m0s
[08:37:42.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:38:0)
[08:37:42.155] Timer tick, asking Buildbucket for the build status
[08:37:42.373] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:42.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:39:0) after 1m0s
[08:38:42.421] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:39:0)
[08:38:42.421] Timer tick, asking Buildbucket for the build status
[08:38:42.775] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:42.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:40:0) after 1m0s
[08:39:42.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:40:0)
[08:39:42.942] Timer tick, asking Buildbucket for the build status
[08:39:43.127] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:43.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:41:0) after 1m0s
[08:40:43.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:41:0)
[08:40:43.175] Timer tick, asking Buildbucket for the build status
[08:40:43.454] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:43.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:42:0) after 1m0s
[08:41:43.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:42:0)
[08:41:43.524] Timer tick, asking Buildbucket for the build status
[08:41:43.732] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:43.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:43:0) after 1m0s
[08:42:43.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:43:0)
[08:42:43.759] Timer tick, asking Buildbucket for the build status
[08:42:43.923] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:43.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:44:0) after 1m0s
[08:43:44.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:44:0)
[08:43:44.052] Timer tick, asking Buildbucket for the build status
[08:43:44.166] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:44.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:45:0) after 1m0s
[08:44:44.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:45:0)
[08:44:44.158] Timer tick, asking Buildbucket for the build status
[08:44:44.287] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:44.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:46:0) after 1m0s
[08:45:44.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:46:0)
[08:45:44.393] Timer tick, asking Buildbucket for the build status
[08:45:45.062] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:45.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:47:0) after 1m0s
[08:46:45.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:47:0)
[08:46:45.110] Timer tick, asking Buildbucket for the build status
[08:46:45.275] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:45.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:48:0) after 1m0s
[08:47:45.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:48:0)
[08:47:45.394] Timer tick, asking Buildbucket for the build status
[08:47:45.538] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:45.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:49:0) after 1m0s
[08:48:45.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:49:0)
[08:48:45.712] Timer tick, asking Buildbucket for the build status
[08:48:46.697] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:46.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:50:0) after 1m0s
[08:49:46.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:50:0)
[08:49:46.732] Timer tick, asking Buildbucket for the build status
[08:49:46.928] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:46.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:51:0) after 1m0s
[08:50:46.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:51:0)
[08:50:46.988] Timer tick, asking Buildbucket for the build status
[08:50:47.159] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:47.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:52:0) after 1m0s
[08:51:47.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:52:0)
[08:51:47.264] Timer tick, asking Buildbucket for the build status
[08:51:47.577] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:47.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:53:0) after 1m0s
[08:52:48.893] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:53:0)
[08:52:48.893] Timer tick, asking Buildbucket for the build status
[08:52:49.363] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:49.363] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:54:0) after 1m0s
[08:53:49.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:54:0)
[08:53:49.830] Timer tick, asking Buildbucket for the build status
[08:53:49.988] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:49.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:55:0) after 1m0s
[08:54:50.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:55:0)
[08:54:50.201] Timer tick, asking Buildbucket for the build status
[08:54:50.364] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:50.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:56:0) after 1m0s
[08:55:50.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:56:0)
[08:55:50.448] Timer tick, asking Buildbucket for the build status
[08:55:50.576] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:50.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:57:0) after 1m0s
[08:56:50.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:57:0)
[08:56:50.758] Timer tick, asking Buildbucket for the build status
[08:56:50.970] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:50.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:58:0) after 1m0s
[08:57:50.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:58:0)
[08:57:50.985] Timer tick, asking Buildbucket for the build status
[08:57:51.129] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:51.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:59:0) after 1m0s
[08:58:51.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:59:0)
[08:58:51.176] Timer tick, asking Buildbucket for the build status
[08:58:51.468] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:51.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:60:0) after 1m0s
[08:59:51.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:60:0)
[08:59:51.635] Timer tick, asking Buildbucket for the build status
[08:59:51.864] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:51.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:61:0) after 1m0s
[09:00:51.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:61:0)
[09:00:51.939] Timer tick, asking Buildbucket for the build status
[09:00:52.243] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:52.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:62:0) after 1m0s
[09:01:52.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:62:0)
[09:01:52.285] Timer tick, asking Buildbucket for the build status
[09:01:52.613] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:52.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:63:0) after 1m0s
[09:02:52.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:63:0)
[09:02:52.635] Timer tick, asking Buildbucket for the build status
[09:02:52.795] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:52.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:64:0) after 1m0s
[09:03:52.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:64:0)
[09:03:52.899] Timer tick, asking Buildbucket for the build status
[09:03:53.050] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:53.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:65:0) after 1m0s
[09:04:53.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:65:0)
[09:04:53.135] Timer tick, asking Buildbucket for the build status
[09:04:53.367] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:53.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:66:0) after 1m0s
[09:05:53.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:66:0)
[09:05:53.419] Timer tick, asking Buildbucket for the build status
[09:05:53.712] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:53.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:67:0) after 1m0s
[09:06:53.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:67:0)
[09:06:53.734] Timer tick, asking Buildbucket for the build status
[09:06:54.174] Build 8894927740227659920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:54.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9060357802409979648:68:0) after 1m0s
[09:07:28.956] Received PubSub notification, asking Buildbucket for the build status
[09:07:29.032] Build 8894927740227659920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:07:29.032] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1575536847145859",
"created_by": "project:emscripten-releases",
"created_ts": "1575532890063470",
"id": "8894927740227659920",
"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\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d39997e6d2903d67b5f61338663f3cd8531e0201\", \"title\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}}",
"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\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d39997e6d2903d67b5f61338663f3cd8531e0201\", \"title\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-w9ta\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d39997e6d2903d67b5f61338663f3cd8531e0201\"}, \"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-w9ta\"], \"image\": [\"chrome-xenial-19112404-1fe53dcaaf2\"], \"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\": [\"4693-40b2cd3\"], \"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": "1575532901046773",
"status": "COMPLETED",
"status_changed_ts": "1575536848114739",
"tags": [
"builder:linux",
"buildset:commit/git/d39997e6d2903d67b5f61338663f3cd8531e0201",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d39997e6d2903d67b5f61338663f3cd8531e0201",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060357802409979648",
"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/8894927740227659920/+/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:48ede594f72e8210",
"user_agent:luci-scheduler"
],
"updated_ts": "1575536848282779",
"url": "https://ci.chromium.org/b/8894927740227659920",
"utcnow_ts": "1575536849022750"
}
}
[09:07:29.032] Invocation finished in 1h6m0.433681218s with status SUCCEEDED