[09:46:46.267] New invocation is queued and will start shortly
[09:46:47.360] Starting the invocation (attempt 1)
[09:46:47.389] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:47.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:47.389] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9017589409729196064",
"parameters_json": "{\"builder_name\":\"mac\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"98b089272b4ea10437f45dbf14ca8ded86f11e69\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@98b089272b4ea10437f45dbf14ca8ded86f11e69\",\"title\":\"98b089272b4ea10437f45dbf14ca8ded86f11e69\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/98b089272b4ea10437f45dbf14ca8ded86f11e69\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"98b089272b4ea10437f45dbf14ca8ded86f11e69\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:mac",
"scheduler_invocation_id:9017589409729196064",
"scheduler_job_id:emscripten-releases/mac",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/98b089272b4ea10437f45dbf14ca8ded86f11e69",
"gitiles_ref:refs/heads/master"
]
}
[09:46:47.988] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1616320007488654",
"id": "8852159347786449024",
"parameters_json": "{\"builder_name\": \"mac\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"98b089272b4ea10437f45dbf14ca8ded86f11e69\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@98b089272b4ea10437f45dbf14ca8ded86f11e69\", \"title\": \"98b089272b4ea10437f45dbf14ca8ded86f11e69\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/98b089272b4ea10437f45dbf14ca8ded86f11e69\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"98b089272b4ea10437f45dbf14ca8ded86f11e69\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616320007748979",
"tags": [
"builder:mac",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/98b089272b4ea10437f45dbf14ca8ded86f11e69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017589409729196064",
"scheduler_job_id:emscripten-releases/mac",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8852159347786449024/+/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": "1616320007750851",
"url": "https://ci.chromium.org/b/8852159347786449024",
"utcnow_ts": "1616320007982056"
}
}
[09:46:47.988] Task URL: https://ci.chromium.org/b/8852159347786449024
[09:46:47.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:2:0) after 1m0s
[09:47:45.692] Received PubSub notification, asking Buildbucket for the build status
[09:47:45.757] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:48.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:2:0)
[09:47:48.003] Timer tick, asking Buildbucket for the build status
[09:47:48.108] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:48.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:4:0) after 1m0s
[09:48:48.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:4:0)
[09:48:48.123] Timer tick, asking Buildbucket for the build status
[09:48:48.464] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:48.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:5:0) after 1m0s
[09:49:48.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:5:0)
[09:49:48.493] Timer tick, asking Buildbucket for the build status
[09:49:48.562] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:48.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:6:0) after 1m0s
[09:50:48.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:6:0)
[09:50:48.623] Build status: STARTED
[09:50:48.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:7:0) after 7m28s
[09:58:16.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:7:0)
[09:58:16.732] Timer tick, asking Buildbucket for the build status
[09:58:16.807] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:16.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:8:0) after 1m0s
[09:59:16.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:8:0)
[09:59:16.991] Build status: STARTED
[09:59:16.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:9:0) after 5m13s
[10:04:30.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:9:0)
[10:04:30.007] Timer tick, asking Buildbucket for the build status
[10:04:30.117] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:30.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:10:0) after 1m0s
[10:05:30.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:10:0)
[10:05:30.139] Timer tick, asking Buildbucket for the build status
[10:05:30.222] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:30.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:11:0) after 1m0s
[10:06:30.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:11:0)
[10:06:30.267] Build status: STARTED
[10:06:30.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:12:0) after 8m7s
[10:14:37.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:12:0)
[10:14:37.395] Build status: STARTED
[10:14:37.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:13:0) after 3m46s
[10:18:23.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:13:0)
[10:18:23.411] Timer tick, asking Buildbucket for the build status
[10:18:23.764] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:23.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:14:0) after 1m0s
[10:19:23.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:14:0)
[10:19:23.807] Build status: STARTED
[10:19:23.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:15:0) after 8m56s
[10:28:20.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:15:0)
[10:28:20.184] Build status: STARTED
[10:28:20.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:16:0) after 4m26s
[10:32:52.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:16:0)
[10:32:52.434] Build status: STARTED
[10:32:52.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:17:0) after 7m59s
[10:40:51.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:17:0)
[10:40:51.636] Build status: STARTED
[10:40:51.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:18:0) after 6m49s
[10:47:40.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:18:0)
[10:47:40.715] Timer tick, asking Buildbucket for the build status
[10:47:40.998] Build 8852159347786449024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:40.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:19:0) after 1m0s
[10:48:41.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:19:0)
[10:48:41.058] Build status: STARTED
[10:48:41.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:20:0) after 6m5s
[10:54:46.074] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:20:0)
[10:54:46.101] Build status: STARTED
[10:54:46.101] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017589409729196064:21:0) after 9m53s
[11:04:11.607] Received PubSub notification, asking Buildbucket for the build status
[11:04:11.645] Build:
{
"id": "8852159347786449024",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2021-03-21T09:46:47.488654Z",
"startTime": "2021-03-21T09:47:44.532032Z",
"endTime": "2021-03-21T11:04:10.733248Z",
"updateTime": "2021-03-21T11:04:11.101927Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "98b089272b4ea10437f45dbf14ca8ded86f11e69",
"ref": "refs/heads/master"
}
}
}
[11:04:11.645] Invocation finished in 1h17m25.393185994s with status SUCCEEDED