[05:57:51.388] New invocation is queued and will start shortly
[05:57:52.480] Starting the invocation (attempt 1)
[05:57:52.513] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd5d32967d263310d966c9f757ece127b126f7ea"
[05:57:52.513] Popped gitiles commit info from properties and tags
[05:57:52.513] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:57:52.513] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:57:52.513] Buildbucket request:
{
"requestId": "8895479101080346400",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "GPU FYI Win x64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d32967d263310d966c9f757ece127b126f7ea",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895479101080346400"
},
{
"key": "scheduler_job_id",
"value": "chromium/GPU FYI Win x64 Builder (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:57:53.078] Scheduled build:
{
"id": "8730049039182492545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "GPU FYI Win x64 Builder (dbg)"
},
"number": 72690,
"createdBy": "project:chromium",
"createTime": "2024-11-28T05:57:52.572365814Z",
"updateTime": "2024-11-28T05:57:52.572365814Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d32967d263310d966c9f757ece127b126f7ea",
"ref": "refs/heads/main"
}
}
}
[05:57:53.078] Task URL: https://cr-buildbucket.appspot.com/build/8730049039182492545
[05:57:53.078] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:2:0) after 9m22s
[05:57:54.102] Received PubSub notification, asking Buildbucket for the build status
[05:57:54.147] Build status: SCHEDULED
[05:59:17.441] Received PubSub notification, asking Buildbucket for the build status
[05:59:17.463] Build status: STARTED
[06:07:15.099] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:2:0)
[06:07:15.189] Build status: STARTED
[06:07:15.189] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:5:0) after 4m44s
[06:11:59.251] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:5:0)
[06:11:59.282] Build status: STARTED
[06:11:59.282] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:6:0) after 2m13s
[06:14:12.302] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:6:0)
[06:14:12.337] Build status: STARTED
[06:14:12.337] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:7:0) after 7m15s
[06:21:27.356] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:7:0)
[06:21:27.374] Build status: STARTED
[06:21:27.374] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:8:0) after 4m15s
[06:25:42.543] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:8:0)
[06:25:42.617] Build status: STARTED
[06:25:42.617] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:9:0) after 9m22s
[06:35:04.828] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:9:0)
[06:35:04.852] Build status: STARTED
[06:35:04.852] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:10:0) after 2m53s
[06:37:58.623] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:10:0)
[06:37:58.719] Build status: STARTED
[06:37:58.719] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:11:0) after 7m28s
[06:45:26.737] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:11:0)
[06:45:26.756] Build status: STARTED
[06:45:26.756] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:12:0) after 2m10s
[06:47:36.882] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:12:0)
[06:47:36.904] Build status: STARTED
[06:47:36.904] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:13:0) after 1m53s
[06:49:29.898] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:13:0)
[06:49:29.918] Build status: STARTED
[06:49:29.918] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:14:0) after 8m31s
[06:58:00.932] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:14:0)
[06:58:00.953] Build status: STARTED
[06:58:00.953] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:15:0) after 4m12s
[07:02:13.018] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:15:0)
[07:02:13.039] Build status: STARTED
[07:02:13.039] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:16:0) after 6m46s
[07:08:59.117] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:16:0)
[07:08:59.139] Build status: STARTED
[07:08:59.139] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:17:0) after 8m53s
[07:17:52.154] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:17:0)
[07:17:52.171] Build status: STARTED
[07:17:52.171] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:18:0) after 8m47s
[07:26:39.692] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:18:0)
[07:26:39.706] Build status: STARTED
[07:26:39.706] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:19:0) after 4m29s
[07:31:08.875] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:19:0)
[07:31:08.895] Build status: STARTED
[07:31:08.895] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:20:0) after 6m56s
[07:38:04.911] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:20:0)
[07:38:04.936] Build status: STARTED
[07:38:04.936] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:21:0) after 5m47s
[07:43:52.194] Handling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:21:0)
[07:43:52.210] Build status: STARTED
[07:43:52.211] Scheduling timer "check-buildbucket-build-status" (chromium/GPU FYI Win x64 Builder (dbg):8895479101080346400:22:0) after 9m30s
[07:48:37.618] Received PubSub notification, asking Buildbucket for the build status
[07:48:37.639] Build:
{
"id": "8730049039182492545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "GPU FYI Win x64 Builder (dbg)"
},
"number": 72690,
"createdBy": "project:chromium",
"createTime": "2024-11-28T05:57:52.572365814Z",
"startTime": "2024-11-28T05:59:17.104641942Z",
"endTime": "2024-11-28T07:48:37.366609369Z",
"updateTime": "2024-11-28T07:48:37.366609369Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fd5d32967d263310d966c9f757ece127b126f7ea",
"ref": "refs/heads/main"
}
}
}
[07:48:37.639] Invocation finished in 1h50m46.260563927s with status SUCCEEDED