[06:31:13.241] New invocation is queued and will start shortly
[06:31:14.303] Starting the invocation (attempt 1)
[06:31:14.328] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/60b8e14f86a7475ead4215ed0e421a5b0e58d32b"
[06:31:14.328] Popped gitiles commit info from properties and tags
[06:31:14.328] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:31:14.328] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:31:14.329] Buildbucket request:
{
"requestId": "8943402797207328224",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-archive-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "60b8e14f86a7475ead4215ed0e421a5b0e58d32b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8943402797207328224"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-archive-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:31:15.919] Scheduled build:
{
"id": "8777972734678590273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-archive-rel"
},
"number": 39835,
"createdBy": "project:chromium",
"createTime": "2023-06-18T06:31:15.030669522Z",
"updateTime": "2023-06-18T06:31:15.030669522Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "60b8e14f86a7475ead4215ed0e421a5b0e58d32b",
"ref": "refs/heads/main"
}
}
}
[06:31:15.919] Task URL: https://cr-buildbucket.appspot.com/build/8777972734678590273
[06:31:15.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:2:0) after 6m53s
[06:36:16.585] Received PubSub notification, asking Buildbucket for the build status
[06:36:16.628] Build status: STARTED
[06:38:08.947] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:2:0)
[06:38:08.965] Build status: STARTED
[06:38:08.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:4:0) after 1m7s
[06:39:15.978] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:4:0)
[06:39:16.022] Build status: STARTED
[06:39:16.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:5:0) after 4m56s
[06:44:12.046] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:5:0)
[06:44:12.063] Build status: STARTED
[06:44:12.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:6:0) after 9m59s
[06:54:11.128] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:6:0)
[06:54:11.144] Build status: STARTED
[06:54:11.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:7:0) after 6m34s
[07:00:45.194] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:7:0)
[07:00:45.245] Build status: STARTED
[07:00:45.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:8:0) after 2m34s
[07:03:19.469] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:8:0)
[07:03:19.494] Build status: STARTED
[07:03:19.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:9:0) after 4m16s
[07:07:35.946] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:9:0)
[07:07:35.959] Build status: STARTED
[07:07:35.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:10:0) after 6m48s
[07:14:23.980] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:10:0)
[07:14:24.006] Build status: STARTED
[07:14:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:11:0) after 6m30s
[07:20:54.026] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:11:0)
[07:20:54.057] Build status: STARTED
[07:20:54.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:12:0) after 3m43s
[07:24:37.279] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:12:0)
[07:24:37.297] Build status: STARTED
[07:24:37.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:13:0) after 9m2s
[07:33:39.310] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:13:0)
[07:33:39.336] Build status: STARTED
[07:33:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:14:0) after 1m44s
[07:35:23.358] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:14:0)
[07:35:23.397] Build status: STARTED
[07:35:23.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:15:0) after 3m15s
[07:38:38.419] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:15:0)
[07:38:38.439] Build status: STARTED
[07:38:38.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:16:0) after 5m59s
[07:44:37.516] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:16:0)
[07:44:37.536] Build status: STARTED
[07:44:37.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:17:0) after 7m24s
[07:52:01.549] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:17:0)
[07:52:01.564] Build status: STARTED
[07:52:01.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:8943402797207328224:18:0) after 7m10s
[07:55:50.921] Received PubSub notification, asking Buildbucket for the build status
[07:55:50.935] Build:
{
"id": "8777972734678590273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-archive-rel"
},
"number": 39835,
"createdBy": "project:chromium",
"createTime": "2023-06-18T06:31:15.030669522Z",
"startTime": "2023-06-18T06:36:10.368233Z",
"endTime": "2023-06-18T07:55:50.626347180Z",
"updateTime": "2023-06-18T07:55:50.626347180Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "60b8e14f86a7475ead4215ed0e421a5b0e58d32b",
"ref": "refs/heads/main"
}
}
}
[07:55:50.935] Invocation finished in 1h24m37.699949561s with status SUCCEEDED