[22:18:12.679] New invocation is queued and will start shortly
[22:18:13.764] Starting the invocation (attempt 1)
[22:18:13.804] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ddc1624637c8cfa8ef50a95abd779e0ba4d67f6"
[22:18:13.804] Popped gitiles commit info from properties and tags
[22:18:13.804] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:18:13.805] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:18:13.806] Buildbucket request:
{
"requestId": "8897319958781359552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ddc1624637c8cfa8ef50a95abd779e0ba4d67f6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897319958781359552"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-win-cross-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:18:14.686] Scheduled build:
{
"id": "8731889896854435137",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"number": 3185,
"createdBy": "project:chromium",
"createTime": "2024-11-07T22:18:13.890058294Z",
"updateTime": "2024-11-07T22:18:13.890058294Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ddc1624637c8cfa8ef50a95abd779e0ba4d67f6",
"ref": "refs/heads/main"
}
}
}
[22:18:14.686] Task URL: https://cr-buildbucket.appspot.com/build/8731889896854435137
[22:18:14.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:2:0) after 8m6s
[22:18:15.534] Received PubSub notification, asking Buildbucket for the build status
[22:18:15.669] Build status: SCHEDULED
[22:18:31.370] Received PubSub notification, asking Buildbucket for the build status
[22:18:31.397] Build status: STARTED
[22:26:20.709] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:2:0)
[22:26:20.744] Build status: STARTED
[22:26:20.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:5:0) after 8m29s
[22:34:49.852] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:5:0)
[22:34:49.875] Build status: STARTED
[22:34:49.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:6:0) after 9m58s
[22:44:47.895] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:6:0)
[22:44:47.922] Build status: STARTED
[22:44:47.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:7:0) after 4m15s
[22:49:02.962] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:7:0)
[22:49:02.981] Build status: STARTED
[22:49:02.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:8:0) after 7m27s
[22:56:30.002] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:8:0)
[22:56:30.019] Build status: STARTED
[22:56:30.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:9:0) after 2m18s
[22:58:48.041] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:9:0)
[22:58:48.069] Build status: STARTED
[22:58:48.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:10:0) after 3m13s
[23:02:01.064] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:10:0)
[23:02:01.085] Build status: STARTED
[23:02:01.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:11:0) after 3m30s
[23:05:31.152] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:11:0)
[23:05:31.183] Build status: STARTED
[23:05:31.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:12:0) after 7m15s
[23:12:46.253] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:12:0)
[23:12:46.269] Build status: STARTED
[23:12:46.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:13:0) after 5m59s
[23:18:45.288] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:13:0)
[23:18:45.341] Build status: STARTED
[23:18:45.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:14:0) after 9m17s
[23:28:02.415] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:14:0)
[23:28:02.442] Build status: STARTED
[23:28:02.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:15:0) after 5m38s
[23:33:40.478] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:15:0)
[23:33:40.508] Build status: STARTED
[23:33:40.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:16:0) after 9m11s
[23:42:51.526] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:16:0)
[23:42:51.545] Build status: STARTED
[23:42:51.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:17:0) after 1m30s
[23:44:21.676] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:17:0)
[23:44:21.754] Build status: STARTED
[23:44:21.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:18:0) after 9m56s
[23:54:17.774] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:18:0)
[23:54:17.790] Build status: STARTED
[23:54:17.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:19:0) after 5m20s
[23:59:38.010] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:19:0)
[23:59:38.028] Build status: STARTED
[23:59:38.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:20:0) after 6m15s
[00:05:53.366] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:20:0)
[00:05:53.450] Build status: STARTED
[00:05:53.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:21:0) after 6m49s
[00:12:42.468] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:21:0)
[00:12:42.567] Build status: STARTED
[00:12:42.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:22:0) after 8m46s
[00:21:28.627] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:22:0)
[00:21:28.684] Build status: STARTED
[00:21:28.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:23:0) after 6m53s
[00:28:21.732] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:23:0)
[00:28:21.752] Build status: STARTED
[00:28:21.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:24:0) after 8m55s
[00:37:17.027] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:24:0)
[00:37:17.048] Build status: STARTED
[00:37:17.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:25:0) after 2m46s
[00:40:03.068] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:25:0)
[00:40:03.117] Build status: STARTED
[00:40:03.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:26:0) after 2m49s
[00:42:52.140] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:26:0)
[00:42:52.196] Build status: STARTED
[00:42:52.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:27:0) after 5m47s
[00:48:39.251] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:27:0)
[00:48:39.268] Build status: STARTED
[00:48:39.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:28:0) after 1m3s
[00:49:42.907] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:28:0)
[00:49:42.926] Build status: STARTED
[00:49:42.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:29:0) after 6m15s
[00:55:58.444] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:29:0)
[00:55:58.466] Build status: STARTED
[00:55:58.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8897319958781359552:30:0) after 5m49s
[00:58:19.116] Received PubSub notification, asking Buildbucket for the build status
[00:58:19.132] Build:
{
"id": "8731889896854435137",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"number": 3185,
"createdBy": "project:chromium",
"createTime": "2024-11-07T22:18:13.890058294Z",
"startTime": "2024-11-07T22:18:30.966922914Z",
"endTime": "2024-11-08T00:58:16.744373770Z",
"updateTime": "2024-11-08T00:58:16.744373770Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ddc1624637c8cfa8ef50a95abd779e0ba4d67f6",
"ref": "refs/heads/main"
}
}
}
[00:58:19.132] Invocation finished in 2h40m6.46304766s with status SUCCEEDED