[22:52:20.756] New invocation is queued and will start shortly
[22:52:21.812] Starting the invocation (attempt 1)
[22:52:21.840] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/670116ddc42ac9f42a29cb2c9bcb14cd5dfa7456"
[22:52:21.840] Popped gitiles commit info from properties and tags
[22:52:21.840] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:52:21.840] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:52:21.840] Buildbucket request:
{
"requestId": "8952763154655486048",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac Builder Next"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670116ddc42ac9f42a29cb2c9bcb14cd5dfa7456",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952763154655486048"
},
{
"key": "scheduler_job_id",
"value": "chromium/Mac Builder Next"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:52:22.440] Scheduled build:
{
"id": "8787333092738734961",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac Builder Next"
},
"number": 68923,
"createdBy": "project:chromium",
"createTime": "2023-03-06T22:52:21.956628582Z",
"updateTime": "2023-03-06T22:52:21.956628582Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670116ddc42ac9f42a29cb2c9bcb14cd5dfa7456",
"ref": "refs/heads/main"
}
}
}
[22:52:22.440] Task URL: https://cr-buildbucket.appspot.com/build/8787333092738734961
[22:52:22.440] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:2:0) after 7m28s
[22:54:09.020] Received PubSub notification, asking Buildbucket for the build status
[22:54:09.055] Build status: STARTED
[22:59:51.009] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:2:0)
[22:59:51.058] Build status: STARTED
[22:59:51.058] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:4:0) after 2m56s
[23:02:47.150] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:4:0)
[23:02:47.177] Build status: STARTED
[23:02:47.177] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:5:0) after 5m20s
[23:08:07.244] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:5:0)
[23:08:07.272] Build status: STARTED
[23:08:07.272] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:6:0) after 4m57s
[23:13:04.296] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:6:0)
[23:13:04.320] Build status: STARTED
[23:13:04.320] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:7:0) after 3m57s
[23:17:01.351] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:7:0)
[23:17:01.372] Build status: STARTED
[23:17:01.372] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:8:0) after 2m35s
[23:19:36.438] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:8:0)
[23:19:36.457] Build status: STARTED
[23:19:36.457] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:9:0) after 3m48s
[23:23:24.599] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:9:0)
[23:23:24.628] Build status: STARTED
[23:23:24.628] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:10:0) after 4m56s
[23:28:21.050] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:10:0)
[23:28:21.095] Build status: STARTED
[23:28:21.095] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:11:0) after 6m8s
[23:34:29.114] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:11:0)
[23:34:29.139] Build status: STARTED
[23:34:29.139] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:12:0) after 1m57s
[23:36:26.239] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:12:0)
[23:36:26.327] Build status: STARTED
[23:36:26.327] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:13:0) after 5m59s
[23:42:25.376] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:13:0)
[23:42:25.402] Build status: STARTED
[23:42:25.402] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:14:0) after 2m34s
[23:44:59.703] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:14:0)
[23:44:59.724] Build status: STARTED
[23:44:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:15:0) after 7m2s
[23:52:02.019] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:15:0)
[23:52:02.052] Build status: STARTED
[23:52:02.052] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:16:0) after 2m43s
[23:54:45.221] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:16:0)
[23:54:45.277] Build status: STARTED
[23:54:45.277] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:17:0) after 3m17s
[23:58:02.450] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:17:0)
[23:58:02.476] Build status: STARTED
[23:58:02.476] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:18:0) after 1m22s
[23:59:24.871] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:18:0)
[23:59:24.921] Build status: STARTED
[23:59:24.921] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:19:0) after 2m18s
[00:01:42.966] Handling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:19:0)
[00:01:42.988] Build status: STARTED
[00:01:42.988] Scheduling timer "check-buildbucket-build-status" (chromium/Mac Builder Next:8952763154655486048:20:0) after 7m31s
[00:04:12.131] Received PubSub notification, asking Buildbucket for the build status
[00:04:12.159] Build:
{
"id": "8787333092738734961",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac Builder Next"
},
"number": 68923,
"createdBy": "project:chromium",
"createTime": "2023-03-06T22:52:21.956628582Z",
"startTime": "2023-03-06T22:54:08.007563Z",
"endTime": "2023-03-07T00:04:11.756215248Z",
"updateTime": "2023-03-07T00:04:11.756215248Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "670116ddc42ac9f42a29cb2c9bcb14cd5dfa7456",
"ref": "refs/heads/main"
}
}
}
[00:04:12.159] Invocation finished in 1h11m51.415011618s with status FAILED