[06:37:24.515] New invocation is queued and will start shortly
[06:37:25.623] Starting the invocation (attempt 1)
[06:37:25.667] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:37:25.667] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:37:25.667] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9017691920362350656",
"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\":\"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\",\"title\":\"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:mac",
"scheduler_invocation_id:9017691920362350656",
"scheduler_job_id:emscripten-releases/mac",
"user_agent:luci-scheduler",
"buildset:commit/git/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af",
"gitiles_ref:refs/heads/master"
]
}
[06:37:26.679] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1616222245791599",
"id": "8852261858355444704",
"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\": \"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\", \"title\": \"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5c3841dafe3d8b181b7a1b9948ecf24b55dd70af\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616222246413915",
"tags": [
"builder:mac",
"buildset:commit/git/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5c3841dafe3d8b181b7a1b9948ecf24b55dd70af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017691920362350656",
"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/8852261858355444704/+/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": "1616222246414043",
"url": "https://ci.chromium.org/b/8852261858355444704",
"utcnow_ts": "1616222246663591"
}
}
[06:37:26.679] Task URL: https://ci.chromium.org/b/8852261858355444704
[06:37:26.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:2:0) after 1m0s
[06:38:23.391] Received PubSub notification, asking Buildbucket for the build status
[06:38:23.497] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:26.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:2:0)
[06:38:26.695] Timer tick, asking Buildbucket for the build status
[06:38:27.085] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:27.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:4:0) after 1m0s
[06:39:27.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:4:0)
[06:39:27.153] Build status: STARTED
[06:39:27.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:5:0) after 7m35s
[06:47:02.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:5:0)
[06:47:02.252] Build status: STARTED
[06:47:02.252] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:6:0) after 2m47s
[06:49:49.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:6:0)
[06:49:49.306] Build status: STARTED
[06:49:49.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:7:0) after 7m17s
[06:57:06.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:7:0)
[06:57:06.592] Timer tick, asking Buildbucket for the build status
[06:57:06.730] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:06.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:8:0) after 1m0s
[06:58:07.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:8:0)
[06:58:07.119] Build status: STARTED
[06:58:07.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:9:0) after 9m37s
[07:07:44.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:9:0)
[07:07:44.298] Build status: STARTED
[07:07:44.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:10:0) after 8m7s
[07:15:51.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:10:0)
[07:15:51.594] Build status: STARTED
[07:15:51.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:11:0) after 7m29s
[07:23:20.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:11:0)
[07:23:20.626] Timer tick, asking Buildbucket for the build status
[07:23:20.719] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:20.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:12:0) after 1m0s
[07:24:20.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:12:0)
[07:24:20.767] Build status: STARTED
[07:24:20.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:13:0) after 2m10s
[07:26:30.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:13:0)
[07:26:30.842] Timer tick, asking Buildbucket for the build status
[07:26:31.296] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:31.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:14:0) after 1m0s
[07:27:31.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:14:0)
[07:27:31.348] Build status: STARTED
[07:27:31.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:15:0) after 5m40s
[07:33:11.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:15:0)
[07:33:11.465] Build status: STARTED
[07:33:11.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:16:0) after 3m23s
[07:36:34.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:16:0)
[07:36:34.617] Build status: STARTED
[07:36:34.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:17:0) after 8m40s
[07:45:14.623] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:17:0)
[07:45:14.650] Build status: STARTED
[07:45:14.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:18:0) after 6m46s
[07:52:01.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:18:0)
[07:52:01.257] Timer tick, asking Buildbucket for the build status
[07:52:01.338] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:01.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:19:0) after 1m0s
[07:53:01.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:19:0)
[07:53:01.596] Timer tick, asking Buildbucket for the build status
[07:53:01.725] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:01.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:20:0) after 1m0s
[07:54:01.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:20:0)
[07:54:01.826] Timer tick, asking Buildbucket for the build status
[07:54:02.155] Build 8852261858355444704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:02.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:9017691920362350656:21:0) after 1m0s
[07:55:02.487] Received PubSub notification, asking Buildbucket for the build status
[07:55:02.522] Build:
{
"id": "8852261858355444704",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2021-03-20T06:37:25.791599Z",
"startTime": "2021-03-20T06:38:22.841805Z",
"endTime": "2021-03-20T07:55:01.473604Z",
"updateTime": "2021-03-20T07:55:01.903325Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "5c3841dafe3d8b181b7a1b9948ecf24b55dd70af",
"ref": "refs/heads/master"
}
}
}
[07:55:02.522] Invocation finished in 1h17m38.027896682s with status SUCCEEDED