[14:32:40.390] New invocation is queued and will start shortly
[14:32:41.462] Starting the invocation (attempt 1)
[14:32:41.488] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94"
[14:32:41.488] Popped gitiles commit info from properties and tags
[14:32:41.488] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:32:41.488] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:32:41.488] Buildbucket request:
{
"requestId": "8930598334574947456",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8930598334574947456",
"job": "r8/linux-run-on-app-dump",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "43426b03ec6b5463d3e62eaaec7ed191204dcd67"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@43426b03ec6b5463d3e62eaaec7ed191204dcd67",
"title": "43426b03ec6b5463d3e62eaaec7ed191204dcd67",
"url": "https://r8.googlesource.com/r8/+/43426b03ec6b5463d3e62eaaec7ed191204dcd67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "e8eb6103cca824fbc47ddcc64cfc9c76613f6a17"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@e8eb6103cca824fbc47ddcc64cfc9c76613f6a17",
"title": "e8eb6103cca824fbc47ddcc64cfc9c76613f6a17",
"url": "https://r8.googlesource.com/r8/+/e8eb6103cca824fbc47ddcc64cfc9c76613f6a17"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "53ad90aea74da6fc811302af2e18071874433479"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@53ad90aea74da6fc811302af2e18071874433479",
"title": "53ad90aea74da6fc811302af2e18071874433479",
"url": "https://r8.googlesource.com/r8/+/53ad90aea74da6fc811302af2e18071874433479"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "43b5cf04fcae4e745564e33ad7ca51832b661904"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@43b5cf04fcae4e745564e33ad7ca51832b661904",
"title": "43b5cf04fcae4e745564e33ad7ca51832b661904",
"url": "https://r8.googlesource.com/r8/+/43b5cf04fcae4e745564e33ad7ca51832b661904"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94",
"title": "3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94",
"url": "https://r8.googlesource.com/r8/+/3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930598334574947456"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-run-on-app-dump"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:32:42.200] Scheduled build:
{
"id": "8765168272701325617",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"number": 4011,
"createdBy": "project:r8",
"createTime": "2023-11-06T14:32:41.553163930Z",
"updateTime": "2023-11-06T14:32:41.553163930Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94",
"ref": "refs/heads/main"
}
}
}
[14:32:42.200] Task URL: https://cr-buildbucket.appspot.com/build/8765168272701325617
[14:32:42.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:2:0) after 6m46s
[14:32:42.936] Received PubSub notification, asking Buildbucket for the build status
[14:32:42.955] Build status: SCHEDULED
[14:39:28.213] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:2:0)
[14:39:28.234] Build status: SCHEDULED
[14:39:28.234] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:4:0) after 3m22s
[14:42:50.251] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:4:0)
[14:42:50.268] Build status: SCHEDULED
[14:42:50.268] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:5:0) after 2m1s
[14:44:51.306] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:5:0)
[14:44:51.587] Build status: SCHEDULED
[14:44:51.587] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:6:0) after 7m23s
[14:52:14.798] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:6:0)
[14:52:14.820] Build status: SCHEDULED
[14:52:14.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:7:0) after 5m35s
[14:57:50.280] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:7:0)
[14:57:50.346] Build status: SCHEDULED
[14:57:50.346] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:8:0) after 4m55s
[15:02:45.614] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:8:0)
[15:02:45.633] Build status: SCHEDULED
[15:02:45.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:9:0) after 5m6s
[15:07:51.611] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:9:0)
[15:07:51.630] Build status: SCHEDULED
[15:07:51.630] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:10:0) after 1m42s
[15:09:33.646] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:10:0)
[15:09:33.697] Build status: SCHEDULED
[15:09:33.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:11:0) after 9m6s
[15:18:39.681] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:11:0)
[15:18:39.702] Build status: SCHEDULED
[15:18:39.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:12:0) after 5m8s
[15:23:47.749] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:12:0)
[15:23:47.784] Build status: SCHEDULED
[15:23:47.784] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:13:0) after 7m23s
[15:31:10.804] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:13:0)
[15:31:10.893] Build status: SCHEDULED
[15:31:10.893] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:14:0) after 8m59s
[15:40:04.899] Received PubSub notification, asking Buildbucket for the build status
[15:40:04.932] Build status: STARTED
[15:40:09.914] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:14:0)
[15:40:09.935] Build status: STARTED
[15:40:09.935] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:16:0) after 1m11s
[15:41:20.974] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:16:0)
[15:41:21.007] Build status: STARTED
[15:41:21.007] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:17:0) after 9m50s
[15:51:11.116] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:17:0)
[15:51:11.147] Build status: STARTED
[15:51:11.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:18:0) after 6m23s
[15:57:34.226] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:18:0)
[15:57:34.285] Build status: STARTED
[15:57:34.286] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:19:0) after 9m41s
[16:07:15.310] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:19:0)
[16:07:15.331] Build status: STARTED
[16:07:15.331] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:20:0) after 1m4s
[16:08:19.324] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:20:0)
[16:08:19.356] Build status: STARTED
[16:08:19.356] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:21:0) after 3m32s
[16:11:51.408] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:21:0)
[16:11:51.428] Build status: STARTED
[16:11:51.428] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:22:0) after 1m29s
[16:13:20.444] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:22:0)
[16:13:20.466] Build status: STARTED
[16:13:20.466] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:23:0) after 3m49s
[16:17:09.562] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:23:0)
[16:17:09.734] Build status: STARTED
[16:17:09.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:24:0) after 4m24s
[16:21:33.762] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:24:0)
[16:21:33.794] Build status: STARTED
[16:21:33.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:25:0) after 7m46s
[16:29:19.808] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:25:0)
[16:29:19.948] Build status: STARTED
[16:29:19.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:26:0) after 7m45s
[16:37:05.067] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:26:0)
[16:37:05.099] Build status: STARTED
[16:37:05.099] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:27:0) after 6m11s
[16:43:16.108] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:27:0)
[16:43:16.191] Build status: STARTED
[16:43:16.192] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:28:0) after 7m31s
[16:50:47.215] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:28:0)
[16:50:47.284] Build status: STARTED
[16:50:47.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:29:0) after 1m48s
[16:52:35.343] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:29:0)
[16:52:35.570] Build status: STARTED
[16:52:35.570] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:30:0) after 1m0s
[16:53:35.591] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:30:0)
[16:53:35.631] Build status: STARTED
[16:53:35.631] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:31:0) after 8m7s
[17:01:42.694] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:31:0)
[17:01:42.761] Build status: STARTED
[17:01:42.761] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:32:0) after 7m49s
[17:09:31.927] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:32:0)
[17:09:31.945] Build status: STARTED
[17:09:31.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:33:0) after 8m39s
[17:18:10.972] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:33:0)
[17:18:10.994] Build status: STARTED
[17:18:10.994] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:8930598334574947456:34:0) after 4m23s
[17:20:33.055] Received PubSub notification, asking Buildbucket for the build status
[17:20:33.185] Build:
{
"id": "8765168272701325617",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-run-on-app-dump"
},
"number": 4011,
"createdBy": "project:r8",
"createTime": "2023-11-06T14:32:41.553163930Z",
"startTime": "2023-11-06T15:40:04.436113Z",
"endTime": "2023-11-06T17:20:31.846797Z",
"updateTime": "2023-11-06T17:20:31.846797Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3a2cefc72d2ccb39bb1ab6f12cd818c5d17fba94",
"ref": "refs/heads/main"
}
}
}
[17:20:33.185] Invocation finished in 2h47m52.801867486s with status SUCCEEDED