[20:00:00.059] New invocation is queued and will start shortly
[20:00:01.447] Starting the invocation (attempt 1)
[20:00:01.490] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:00:01.490] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:00:01.490] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9018003813472765424",
"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\":\"a8c66c45e7ae7295fdc647906167be0e556df282\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a8c66c45e7ae7295fdc647906167be0e556df282\",\"title\":\"a8c66c45e7ae7295fdc647906167be0e556df282\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"a8c66c45e7ae7295fdc647906167be0e556df282\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9018003813472765424",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a8c66c45e7ae7295fdc647906167be0e556df282",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282",
"gitiles_ref:refs/heads/master"
]
}
[20:00:02.060] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1615924801637234",
"id": "8852573751156620288",
"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\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a8c66c45e7ae7295fdc647906167be0e556df282\", \"title\": \"a8c66c45e7ae7295fdc647906167be0e556df282\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615924801881398",
"tags": [
"builder:linux",
"buildset:commit/git/a8c66c45e7ae7295fdc647906167be0e556df282",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018003813472765424",
"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/8852573751156620288/+/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": "1615924801881534",
"url": "https://ci.chromium.org/b/8852573751156620288",
"utcnow_ts": "1615924802052636"
}
}
[20:00:02.060] Task URL: https://ci.chromium.org/b/8852573751156620288
[20:00:02.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:2:0) after 1m0s
[20:00:17.917] Received PubSub notification, asking Buildbucket for the build status
[20:00:18.014] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:2:0)
[20:01:02.076] Timer tick, asking Buildbucket for the build status
[20:01:02.378] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.378] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:4:0) after 1m0s
[20:02:02.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:4:0)
[20:02:02.396] Timer tick, asking Buildbucket for the build status
[20:02:02.486] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:02.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:5:0) after 1m0s
[20:03:02.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:5:0)
[20:03:02.479] Timer tick, asking Buildbucket for the build status
[20:03:02.565] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:02.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:6:0) after 1m0s
[20:04:02.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:6:0)
[20:04:02.574] Timer tick, asking Buildbucket for the build status
[20:04:02.637] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:02.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:7:0) after 1m0s
[20:05:02.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:7:0)
[20:05:02.671] Timer tick, asking Buildbucket for the build status
[20:05:02.747] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:02.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:8:0) after 1m0s
[20:06:02.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:8:0)
[20:06:02.764] Timer tick, asking Buildbucket for the build status
[20:06:02.912] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:02.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:9:0) after 1m0s
[20:07:03.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:9:0)
[20:07:03.003] Timer tick, asking Buildbucket for the build status
[20:07:03.354] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:03.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:10:0) after 1m0s
[20:08:03.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:10:0)
[20:08:03.371] Timer tick, asking Buildbucket for the build status
[20:08:03.455] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:03.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:11:0) after 1m0s
[20:09:03.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:11:0)
[20:09:03.469] Timer tick, asking Buildbucket for the build status
[20:09:03.528] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:03.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:12:0) after 1m0s
[20:10:03.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:12:0)
[20:10:03.532] Timer tick, asking Buildbucket for the build status
[20:10:03.795] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:03.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:13:0) after 1m0s
[20:11:03.852] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:13:0)
[20:11:03.852] Timer tick, asking Buildbucket for the build status
[20:11:03.942] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:03.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:14:0) after 1m0s
[20:12:03.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:14:0)
[20:12:03.928] Timer tick, asking Buildbucket for the build status
[20:12:04.115] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:04.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:15:0) after 1m0s
[20:13:04.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:15:0)
[20:13:04.157] Timer tick, asking Buildbucket for the build status
[20:13:04.295] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:04.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:16:0) after 1m0s
[20:14:04.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:16:0)
[20:14:04.334] Timer tick, asking Buildbucket for the build status
[20:14:04.759] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:04.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:17:0) after 1m0s
[20:15:04.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:17:0)
[20:15:04.771] Timer tick, asking Buildbucket for the build status
[20:15:04.867] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:04.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:18:0) after 1m0s
[20:16:04.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:18:0)
[20:16:04.988] Timer tick, asking Buildbucket for the build status
[20:16:05.094] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:05.094] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:19:0) after 1m0s
[20:17:05.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:19:0)
[20:17:05.202] Timer tick, asking Buildbucket for the build status
[20:17:05.654] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:05.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:20:0) after 1m0s
[20:18:05.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:20:0)
[20:18:05.690] Timer tick, asking Buildbucket for the build status
[20:18:06.510] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:06.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:21:0) after 1m0s
[20:19:06.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:21:0)
[20:19:06.604] Timer tick, asking Buildbucket for the build status
[20:19:07.534] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:07.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:22:0) after 1m0s
[20:20:07.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:22:0)
[20:20:07.571] Timer tick, asking Buildbucket for the build status
[20:20:07.638] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:07.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:23:0) after 1m0s
[20:21:07.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:23:0)
[20:21:07.678] Timer tick, asking Buildbucket for the build status
[20:21:07.796] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:07.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:24:0) after 1m0s
[20:22:07.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:24:0)
[20:22:07.846] Timer tick, asking Buildbucket for the build status
[20:22:07.914] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:07.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:25:0) after 1m0s
[20:23:07.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:25:0)
[20:23:07.911] Timer tick, asking Buildbucket for the build status
[20:23:07.990] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:07.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:26:0) after 1m0s
[20:24:08.223] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:26:0)
[20:24:08.223] Timer tick, asking Buildbucket for the build status
[20:24:08.291] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:08.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:27:0) after 1m0s
[20:25:08.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:27:0)
[20:25:08.345] Timer tick, asking Buildbucket for the build status
[20:25:08.437] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:08.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:28:0) after 1m0s
[20:26:08.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:28:0)
[20:26:08.503] Timer tick, asking Buildbucket for the build status
[20:26:08.570] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:08.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:29:0) after 1m0s
[20:27:08.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:29:0)
[20:27:08.842] Timer tick, asking Buildbucket for the build status
[20:27:08.951] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:08.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:30:0) after 1m0s
[20:28:09.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:30:0)
[20:28:09.033] Timer tick, asking Buildbucket for the build status
[20:28:09.316] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:09.316] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:31:0) after 1m0s
[20:29:09.421] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:31:0)
[20:29:09.421] Timer tick, asking Buildbucket for the build status
[20:29:09.489] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:09.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:32:0) after 1m0s
[20:30:09.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:32:0)
[20:30:09.510] Timer tick, asking Buildbucket for the build status
[20:30:09.705] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:09.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:33:0) after 1m0s
[20:31:09.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:33:0)
[20:31:09.727] Timer tick, asking Buildbucket for the build status
[20:31:09.792] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:09.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:34:0) after 1m0s
[20:32:09.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:34:0)
[20:32:09.805] Timer tick, asking Buildbucket for the build status
[20:32:10.013] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:10.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:35:0) after 1m0s
[20:33:10.074] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:35:0)
[20:33:10.074] Timer tick, asking Buildbucket for the build status
[20:33:10.152] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:10.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:36:0) after 1m0s
[20:34:10.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:36:0)
[20:34:10.224] Timer tick, asking Buildbucket for the build status
[20:34:10.293] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:10.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:37:0) after 1m0s
[20:35:10.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:37:0)
[20:35:10.320] Timer tick, asking Buildbucket for the build status
[20:35:10.425] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:10.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:38:0) after 1m0s
[20:36:10.459] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:38:0)
[20:36:10.459] Timer tick, asking Buildbucket for the build status
[20:36:10.527] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:10.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:39:0) after 1m0s
[20:37:10.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:39:0)
[20:37:10.553] Timer tick, asking Buildbucket for the build status
[20:37:10.689] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:10.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:40:0) after 1m0s
[20:38:10.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:40:0)
[20:38:10.714] Timer tick, asking Buildbucket for the build status
[20:38:10.793] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:10.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:41:0) after 1m0s
[20:39:10.849] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:41:0)
[20:39:10.849] Timer tick, asking Buildbucket for the build status
[20:39:10.924] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:10.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:42:0) after 1m0s
[20:40:10.972] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:42:0)
[20:40:10.972] Timer tick, asking Buildbucket for the build status
[20:40:11.066] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:11.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:43:0) after 1m0s
[20:41:11.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:43:0)
[20:41:11.116] Timer tick, asking Buildbucket for the build status
[20:41:11.190] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:11.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:44:0) after 1m0s
[20:42:11.252] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:44:0)
[20:42:11.252] Timer tick, asking Buildbucket for the build status
[20:42:11.332] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:11.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:45:0) after 1m0s
[20:43:11.434] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:45:0)
[20:43:11.434] Timer tick, asking Buildbucket for the build status
[20:43:11.553] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:11.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:46:0) after 1m0s
[20:44:11.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:46:0)
[20:44:11.690] Timer tick, asking Buildbucket for the build status
[20:44:11.778] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:11.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:47:0) after 1m0s
[20:45:11.816] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:47:0)
[20:45:11.816] Timer tick, asking Buildbucket for the build status
[20:45:11.907] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:11.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:48:0) after 1m0s
[20:46:12.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:48:0)
[20:46:12.031] Timer tick, asking Buildbucket for the build status
[20:46:12.227] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:12.227] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:49:0) after 1m0s
[20:47:12.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:49:0)
[20:47:12.300] Timer tick, asking Buildbucket for the build status
[20:47:12.471] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:12.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:50:0) after 1m0s
[20:48:12.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:50:0)
[20:48:12.635] Timer tick, asking Buildbucket for the build status
[20:48:12.836] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:12.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:51:0) after 1m0s
[20:49:12.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:51:0)
[20:49:12.918] Timer tick, asking Buildbucket for the build status
[20:49:12.998] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:12.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:52:0) after 1m0s
[20:50:13.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:52:0)
[20:50:13.011] Timer tick, asking Buildbucket for the build status
[20:50:13.402] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:13.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:53:0) after 1m0s
[20:51:13.424] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:53:0)
[20:51:13.424] Timer tick, asking Buildbucket for the build status
[20:51:13.533] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:13.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:54:0) after 1m0s
[20:52:13.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:54:0)
[20:52:13.545] Timer tick, asking Buildbucket for the build status
[20:52:13.610] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:13.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:55:0) after 1m0s
[20:53:13.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:55:0)
[20:53:13.674] Timer tick, asking Buildbucket for the build status
[20:53:14.038] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:14.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:56:0) after 1m0s
[20:54:14.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:56:0)
[20:54:14.213] Timer tick, asking Buildbucket for the build status
[20:54:14.305] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:14.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:57:0) after 1m0s
[20:55:14.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:57:0)
[20:55:14.413] Timer tick, asking Buildbucket for the build status
[20:55:14.727] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:14.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:58:0) after 1m0s
[20:56:14.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:58:0)
[20:56:14.746] Timer tick, asking Buildbucket for the build status
[20:56:14.830] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:14.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:59:0) after 1m0s
[20:57:14.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:59:0)
[20:57:14.799] Timer tick, asking Buildbucket for the build status
[20:57:14.861] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:14.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:60:0) after 1m0s
[20:58:14.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:60:0)
[20:58:14.912] Timer tick, asking Buildbucket for the build status
[20:58:16.045] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:16.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:61:0) after 1m0s
[20:59:16.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:61:0)
[20:59:16.132] Timer tick, asking Buildbucket for the build status
[20:59:16.375] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:16.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:62:0) after 1m0s
[21:00:16.459] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:62:0)
[21:00:16.459] Timer tick, asking Buildbucket for the build status
[21:00:16.542] Build 8852573751156620288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:16.542] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9018003813472765424:63:0) after 1m0s
[21:00:34.702] Received PubSub notification, asking Buildbucket for the build status
[21:00:34.805] Build 8852573751156620288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:00:34.805] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1615928434042084",
"created_by": "project:emscripten-releases",
"created_ts": "1615924801637234",
"id": "8852573751156620288",
"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\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a8c66c45e7ae7295fdc647906167be0e556df282\", \"title\": \"a8c66c45e7ae7295fdc647906167be0e556df282\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$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\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a8c66c45e7ae7295fdc647906167be0e556df282\", \"title\": \"a8c66c45e7ae7295fdc647906167be0e556df282\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-2-xzjn\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"a8c66c45e7ae7295fdc647906167be0e556df282\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a8c66c45e7ae7295fdc647906167be0e556df282\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_461cb3affc6f270af3ac9b28cfd8af8c60e3aebc1d1ae3ebd8f2aa2c00b55fae_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-2-xzjn\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615924816702988",
"status": "COMPLETED",
"status_changed_ts": "1615928434325600",
"tags": [
"builder:linux",
"buildset:commit/git/a8c66c45e7ae7295fdc647906167be0e556df282",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a8c66c45e7ae7295fdc647906167be0e556df282",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018003813472765424",
"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/8852573751156620288/+/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:5255714c97522a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615928434339640",
"url": "https://ci.chromium.org/b/8852573751156620288",
"utcnow_ts": "1615928434798621"
}
}
[21:00:34.805] Invocation finished in 1h0m34.76036259s with status SUCCEEDED