[16:58:52.995] New invocation is queued and will start shortly
[16:58:54.465] Starting the invocation (attempt 1)
[16:58:54.516] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9fccfa9793724100bc43b83c2f1f9bc63d81bbf5"
[16:58:54.516] Popped gitiles commit info from properties and tags
[16:58:54.516] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:58:54.516] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:58:54.516] Buildbucket request:
{
"requestId": "8915187651553864352",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9fccfa9793724100bc43b83c2f1f9bc63d81bbf5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915187651553864352"
},
{
"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"
}
}
[16:58:58.311] Scheduled build:
{
"id": "8749757586349793905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"number": 260,
"createdBy": "project:chromium",
"createTime": "2024-04-24T16:58:57.325209803Z",
"updateTime": "2024-04-24T16:58:57.325209803Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9fccfa9793724100bc43b83c2f1f9bc63d81bbf5",
"ref": "refs/heads/main"
}
}
}
[16:58:58.311] Task URL: https://cr-buildbucket.appspot.com/build/8749757586349793905
[16:58:58.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:2:0) after 8m46s
[16:59:16.282] Received PubSub notification, asking Buildbucket for the build status
[16:59:16.367] Build status: STARTED
[16:59:22.604] Received PubSub notification, asking Buildbucket for the build status
[16:59:22.656] Build status: STARTED
[17:07:44.459] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:2:0)
[17:07:44.477] Build status: STARTED
[17:07:44.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:5:0) after 5m13s
[17:12:57.511] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:5:0)
[17:12:57.542] Build status: STARTED
[17:12:57.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:6:0) after 7m44s
[17:20:41.579] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:6:0)
[17:20:41.602] Build status: STARTED
[17:20:41.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:7:0) after 3m4s
[17:23:45.622] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:7:0)
[17:23:45.644] Build status: STARTED
[17:23:45.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:8:0) after 5m40s
[17:29:25.662] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:8:0)
[17:29:25.681] Build status: STARTED
[17:29:25.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:9:0) after 3m42s
[17:33:07.686] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:9:0)
[17:33:07.762] Build status: STARTED
[17:33:07.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:10:0) after 1m32s
[17:34:39.829] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:10:0)
[17:34:39.850] Build status: STARTED
[17:34:39.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:11:0) after 4m1s
[17:38:40.991] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:11:0)
[17:38:41.024] Build status: STARTED
[17:38:41.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:12:0) after 6m19s
[17:45:00.051] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:12:0)
[17:45:00.072] Build status: STARTED
[17:45:00.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:13:0) after 1m8s
[17:46:08.148] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:13:0)
[17:46:08.182] Build status: STARTED
[17:46:08.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:14:0) after 9m8s
[17:55:16.221] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:14:0)
[17:55:16.252] Build status: STARTED
[17:55:16.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:15:0) after 1m9s
[17:56:25.297] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:15:0)
[17:56:25.319] Build status: STARTED
[17:56:25.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:16:0) after 6m5s
[18:02:30.479] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:16:0)
[18:02:30.510] Build status: STARTED
[18:02:30.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:17:0) after 6m45s
[18:09:15.569] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:17:0)
[18:09:15.598] Build status: STARTED
[18:09:15.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:18:0) after 8m17s
[18:17:32.679] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:18:0)
[18:17:32.717] Build status: STARTED
[18:17:32.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:19:0) after 1m48s
[18:19:20.744] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:19:0)
[18:19:20.827] Build status: STARTED
[18:19:20.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:20:0) after 8m3s
[18:27:23.857] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:20:0)
[18:27:23.886] Build status: STARTED
[18:27:23.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:21:0) after 4m27s
[18:31:50.960] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:21:0)
[18:31:50.999] Build status: STARTED
[18:31:50.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:22:0) after 2m5s
[18:33:56.025] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:22:0)
[18:33:56.048] Build status: STARTED
[18:33:56.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:23:0) after 4m56s
[18:38:52.072] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:23:0)
[18:38:52.089] Build status: STARTED
[18:38:52.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:24:0) after 5m48s
[18:44:40.121] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:24:0)
[18:44:40.147] Build status: STARTED
[18:44:40.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:25:0) after 4m15s
[18:48:55.168] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:25:0)
[18:48:55.190] Build status: STARTED
[18:48:55.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:26:0) after 3m48s
[18:52:43.228] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:26:0)
[18:52:43.245] Build status: STARTED
[18:52:43.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:27:0) after 7m38s
[19:00:21.554] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:27:0)
[19:00:21.596] Build status: STARTED
[19:00:21.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:28:0) after 8m42s
[19:09:03.713] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:28:0)
[19:09:03.734] Build status: STARTED
[19:09:03.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:29:0) after 9m9s
[19:18:12.894] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:29:0)
[19:18:12.917] Build status: STARTED
[19:18:12.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:30:0) after 5m10s
[19:23:23.226] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:30:0)
[19:23:23.273] Build status: STARTED
[19:23:23.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:31:0) after 8m8s
[19:31:31.363] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:31:0)
[19:31:31.387] Build status: STARTED
[19:31:31.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:32:0) after 1m29s
[19:33:00.728] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:32:0)
[19:33:00.757] Build status: STARTED
[19:33:00.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:33:0) after 8m50s
[19:41:50.995] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:33:0)
[19:41:51.021] Build status: STARTED
[19:41:51.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:34:0) after 3m27s
[19:45:18.476] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:34:0)
[19:45:18.511] Build status: STARTED
[19:45:18.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:35:0) after 1m32s
[19:46:50.618] Handling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:35:0)
[19:46:50.644] Build status: STARTED
[19:46:50.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-win-cross-rel:8915187651553864352:36:0) after 9m24s
[19:49:22.033] Received PubSub notification, asking Buildbucket for the build status
[19:49:22.270] Build:
{
"id": "8749757586349793905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-win-cross-rel"
},
"number": 260,
"createdBy": "project:chromium",
"createTime": "2024-04-24T16:58:57.325209803Z",
"startTime": "2024-04-24T16:59:04.713852729Z",
"endTime": "2024-04-24T19:49:21.231998172Z",
"updateTime": "2024-04-24T19:49:21.231998172Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9fccfa9793724100bc43b83c2f1f9bc63d81bbf5",
"ref": "refs/heads/main"
}
}
}
[19:49:22.270] Invocation finished in 2h50m29.292214974s with status SUCCEEDED