[05:26:12.064] New invocation is queued and will start shortly
[05:26:13.128] Starting the invocation (attempt 1)
[05:26:13.163] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d780a4e0f5872d0c4b0f26e1f2b568872c6ed7db"
[05:26:13.163] Popped gitiles commit info from properties and tags
[05:26:13.163] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:26:13.164] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[05:26:13.165] Buildbucket request:
{
"requestId": "8912694516277333312",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d780a4e0f5872d0c4b0f26e1f2b568872c6ed7db",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8912694516277333312"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-local-ssd-rel-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[05:26:14.045] Scheduled build:
{
"id": "8747264454403000801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"number": 11936,
"createdBy": "project:chromium",
"createTime": "2024-05-22T05:26:13.230140365Z",
"updateTime": "2024-05-22T05:26:13.230140365Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d780a4e0f5872d0c4b0f26e1f2b568872c6ed7db",
"ref": "refs/heads/main"
}
}
}
[05:26:14.045] Task URL: https://cr-buildbucket-dev.appspot.com/build/8747264454403000801
[05:26:14.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:2:0) after 3m19s
[05:26:14.930] Received PubSub notification, asking Buildbucket for the build status
[05:26:14.958] Build status: SCHEDULED
[05:27:18.282] Received PubSub notification, asking Buildbucket for the build status
[05:27:18.323] Build status: STARTED
[05:29:33.062] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:2:0)
[05:29:33.104] Build status: STARTED
[05:29:33.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:5:0) after 2m44s
[05:32:17.120] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:5:0)
[05:32:17.142] Build status: STARTED
[05:32:17.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:6:0) after 1m34s
[05:33:51.158] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:6:0)
[05:33:51.200] Build status: STARTED
[05:33:51.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:7:0) after 2m30s
[05:36:21.226] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:7:0)
[05:36:21.256] Build status: STARTED
[05:36:21.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:8:0) after 6m45s
[05:43:06.274] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:8:0)
[05:43:06.318] Build status: STARTED
[05:43:06.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:9:0) after 2m14s
[05:45:20.338] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:9:0)
[05:45:20.360] Build status: STARTED
[05:45:20.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:10:0) after 3m20s
[05:48:40.377] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:10:0)
[05:48:40.402] Build status: STARTED
[05:48:40.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:11:0) after 6m28s
[05:55:08.420] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:11:0)
[05:55:08.555] Build status: STARTED
[05:55:08.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:12:0) after 2m14s
[05:57:22.576] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:12:0)
[05:57:22.599] Build status: STARTED
[05:57:22.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:13:0) after 8m29s
[06:05:51.621] Handling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:13:0)
[06:05:51.640] Build status: STARTED
[06:05:51.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-local-ssd-rel-dev:8912694516277333312:14:0) after 3m11s
[06:08:12.880] Received PubSub notification, asking Buildbucket for the build status
[06:08:12.913] Build:
{
"id": "8747264454403000801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-local-ssd-rel-dev"
},
"number": 11936,
"createdBy": "project:chromium",
"createTime": "2024-05-22T05:26:13.230140365Z",
"startTime": "2024-05-22T05:27:16.980575377Z",
"endTime": "2024-05-22T06:08:11.218089639Z",
"updateTime": "2024-05-22T06:08:11.218089639Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d780a4e0f5872d0c4b0f26e1f2b568872c6ed7db",
"ref": "refs/heads/main"
}
}
}
[06:08:12.913] Invocation finished in 42m0.854617378s with status SUCCEEDED