[20:24:14.472] New invocation is queued and will start shortly
[20:24:16.851] Starting the invocation (attempt 1)
[20:24:16.895] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f3d1b1ca418e0df4584cf72b60e342e098ec8d8"
[20:24:16.895] Popped gitiles commit info from properties and tags
[20:24:16.895] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:24:16.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:24:16.895] Buildbucket request:
{
"requestId": "8967992762957061104",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f3d1b1ca418e0df4584cf72b60e342e098ec8d8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8967992762957061104"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMac (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:24:17.931] Scheduled build:
{
"id": "8802562699642637249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 29441,
"createdBy": "project:chromium",
"createTime": "2022-09-19T20:24:17.003036047Z",
"updateTime": "2022-09-19T20:24:17.003036047Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f3d1b1ca418e0df4584cf72b60e342e098ec8d8",
"ref": "refs/heads/main"
}
}
}
[20:24:17.931] Task URL: https://cr-buildbucket.appspot.com/build/8802562699642637249
[20:24:17.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:2:0) after 8m51s
[20:33:08.952] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:2:0)
[20:33:09.094] Build status: SCHEDULED
[20:33:09.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:3:0) after 1m42s
[20:34:51.117] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:3:0)
[20:34:51.143] Build status: SCHEDULED
[20:34:51.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:4:0) after 7m7s
[20:41:58.250] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:4:0)
[20:41:58.283] Build status: SCHEDULED
[20:41:58.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:5:0) after 9m28s
[20:51:26.283] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:5:0)
[20:51:26.324] Build status: SCHEDULED
[20:51:26.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:6:0) after 6m9s
[20:57:35.457] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:6:0)
[20:57:35.489] Build status: SCHEDULED
[20:57:35.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:7:0) after 6m38s
[21:04:13.559] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:7:0)
[21:04:13.584] Build status: SCHEDULED
[21:04:13.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:8:0) after 2m49s
[21:06:05.500] Received PubSub notification, asking Buildbucket for the build status
[21:06:05.529] Build status: STARTED
[21:07:02.861] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:8:0)
[21:07:02.888] Build status: STARTED
[21:07:02.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:10:0) after 7m58s
[21:15:00.965] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:10:0)
[21:15:01.001] Build status: STARTED
[21:15:01.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:11:0) after 5m48s
[21:20:49.132] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:11:0)
[21:20:49.175] Build status: STARTED
[21:20:49.175] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8967992762957061104:12:0) after 9m17s
[21:23:06.609] Received PubSub notification, asking Buildbucket for the build status
[21:23:06.634] Build:
{
"id": "8802562699642637249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 29441,
"createdBy": "project:chromium",
"createTime": "2022-09-19T20:24:17.003036047Z",
"startTime": "2022-09-19T21:06:04.522275Z",
"endTime": "2022-09-19T21:23:06.059707053Z",
"updateTime": "2022-09-19T21:23:06.059707053Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f3d1b1ca418e0df4584cf72b60e342e098ec8d8",
"ref": "refs/heads/main"
}
}
}
[21:23:06.634] Invocation finished in 58m52.175638615s with status FAILED