[21:08:30.790] New invocation is queued and will start shortly
[21:08:32.011] Starting the invocation (attempt 1)
[21:08:32.041] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34d2164afded427e3bca96a1b7f33ce45d811189"
[21:08:32.041] Popped gitiles commit info from properties and tags
[21:08:32.041] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:08:32.041] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:08:32.041] Buildbucket request:
{
"requestId": "8957027744667311808",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34d2164afded427e3bca96a1b7f33ce45d811189",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8957027744667311808"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:08:32.517] Scheduled build:
{
"id": "8791597682616852529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 31599,
"createdBy": "project:chromium",
"createTime": "2023-01-18T21:08:32.119419431Z",
"updateTime": "2023-01-18T21:08:32.119419431Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34d2164afded427e3bca96a1b7f33ce45d811189",
"ref": "refs/heads/main"
}
}
}
[21:08:32.517] Task URL: https://cr-buildbucket.appspot.com/build/8791597682616852529
[21:08:32.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:2:0) after 3m50s
[21:11:33.433] Received PubSub notification, asking Buildbucket for the build status
[21:11:33.456] Build status: STARTED
[21:12:22.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:2:0)
[21:12:22.590] Build status: STARTED
[21:12:22.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:4:0) after 2m42s
[21:15:04.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:4:0)
[21:15:04.830] Build status: STARTED
[21:15:04.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:5:0) after 6m39s
[21:21:44.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:5:0)
[21:21:44.744] Build status: STARTED
[21:21:44.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:6:0) after 5m49s
[21:27:33.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:6:0)
[21:27:33.763] Build status: STARTED
[21:27:33.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:7:0) after 7m49s
[21:35:22.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:7:0)
[21:35:22.805] Build status: STARTED
[21:35:22.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:8:0) after 3m8s
[21:38:31.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:8:0)
[21:38:31.109] Build status: STARTED
[21:38:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:9:0) after 2m16s
[21:40:47.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:9:0)
[21:40:47.183] Build status: STARTED
[21:40:47.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:10:0) after 9m43s
[21:50:30.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:10:0)
[21:50:30.445] Build status: STARTED
[21:50:30.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:11:0) after 7m51s
[21:58:21.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:11:0)
[21:58:21.631] Build status: STARTED
[21:58:21.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:12:0) after 4m18s
[22:02:39.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:12:0)
[22:02:39.771] Build status: STARTED
[22:02:39.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:13:0) after 6m40s
[22:09:19.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:13:0)
[22:09:19.932] Build status: STARTED
[22:09:19.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:14:0) after 3m27s
[22:12:47.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:14:0)
[22:12:47.056] Build status: STARTED
[22:12:47.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:15:0) after 8m37s
[22:21:24.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:15:0)
[22:21:24.114] Build status: STARTED
[22:21:24.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:16:0) after 6m9s
[22:27:33.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:16:0)
[22:27:33.232] Build status: STARTED
[22:27:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:17:0) after 8m58s
[22:36:31.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:17:0)
[22:36:31.336] Build status: STARTED
[22:36:31.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:18:0) after 1m5s
[22:37:36.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:18:0)
[22:37:36.378] Build status: STARTED
[22:37:36.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:19:0) after 8m34s
[22:46:10.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:19:0)
[22:46:10.392] Build status: STARTED
[22:46:10.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:20:0) after 4m24s
[22:50:34.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:20:0)
[22:50:34.466] Build status: STARTED
[22:50:34.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:21:0) after 3m38s
[22:54:12.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:21:0)
[22:54:12.727] Build status: STARTED
[22:54:12.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:22:0) after 5m1s
[22:59:14.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:22:0)
[22:59:14.135] Build status: STARTED
[22:59:14.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8957027744667311808:23:0) after 4m20s
[22:59:48.990] Received PubSub notification, asking Buildbucket for the build status
[22:59:49.031] Build:
{
"id": "8791597682616852529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 31599,
"createdBy": "project:chromium",
"createTime": "2023-01-18T21:08:32.119419431Z",
"startTime": "2023-01-18T21:11:30.758999Z",
"endTime": "2023-01-18T22:59:48.301294041Z",
"updateTime": "2023-01-18T22:59:48.301294041Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34d2164afded427e3bca96a1b7f33ce45d811189",
"ref": "refs/heads/main"
}
}
}
[22:59:49.031] Invocation finished in 1h51m18.250628931s with status SUCCEEDED