[05:00:02.362] New invocation is queued and will start shortly
[05:00:03.444] Starting the invocation (attempt 1)
[05:00:03.473] Ignoring gitiles_ref tag without the buildset tag
[05:00:03.473] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:00:03.473] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:00:03.473] Buildbucket request:
{
"requestId": "8925651528433558736",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "cts-roller"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8925651528433558736",
"job": "dawn/cts-roller",
"triggers": [
{
"cron": {
"generation": "417"
},
"id": "cron:v1:417"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8925651528433558736"
},
{
"key": "scheduler_job_id",
"value": "dawn/cts-roller"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:00:04.023] Scheduled build:
{
"id": "8760221466551847073",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "cts-roller"
},
"createdBy": "project:dawn",
"createTime": "2023-12-31T05:00:03.526694325Z",
"updateTime": "2023-12-31T05:00:03.526694325Z",
"status": "SCHEDULED",
"input": {
}
}
[05:00:04.023] Task URL: https://cr-buildbucket.appspot.com/build/8760221466551847073
[05:00:04.023] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:2:0) after 8m28s
[05:00:04.604] Received PubSub notification, asking Buildbucket for the build status
[05:00:04.635] Build status: SCHEDULED
[05:00:07.320] Received PubSub notification, asking Buildbucket for the build status
[05:00:07.346] Build status: STARTED
[05:08:32.039] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:2:0)
[05:08:32.089] Build status: STARTED
[05:08:32.089] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:5:0) after 2m56s
[05:11:28.154] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:5:0)
[05:11:28.203] Build status: STARTED
[05:11:28.203] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:6:0) after 4m8s
[05:15:36.282] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:6:0)
[05:15:36.338] Build status: STARTED
[05:15:36.338] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:7:0) after 3m56s
[05:19:32.326] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:7:0)
[05:19:32.426] Build status: STARTED
[05:19:32.426] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:8:0) after 1m42s
[05:21:14.439] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:8:0)
[05:21:14.466] Build status: STARTED
[05:21:14.466] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:9:0) after 4m44s
[05:25:58.542] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:9:0)
[05:25:58.557] Build status: STARTED
[05:25:58.557] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:10:0) after 2m48s
[05:28:46.671] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:10:0)
[05:28:46.712] Build status: STARTED
[05:28:46.712] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:11:0) after 8m46s
[05:37:32.745] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:11:0)
[05:37:32.773] Build status: STARTED
[05:37:32.773] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:12:0) after 5m30s
[05:43:02.790] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:12:0)
[05:43:02.883] Build status: STARTED
[05:43:02.883] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:13:0) after 9m45s
[05:52:47.999] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:13:0)
[05:52:48.107] Build status: STARTED
[05:52:48.107] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:14:0) after 7m17s
[06:00:05.124] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:14:0)
[06:00:05.152] Build status: STARTED
[06:00:05.152] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:15:0) after 8m33s
[06:08:38.164] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:15:0)
[06:08:38.229] Build status: STARTED
[06:08:38.229] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:16:0) after 2m23s
[06:11:01.276] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:16:0)
[06:11:01.324] Build status: STARTED
[06:11:01.324] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:17:0) after 1m28s
[06:12:29.342] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:17:0)
[06:12:29.382] Build status: STARTED
[06:12:29.382] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:18:0) after 3m25s
[06:15:54.422] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:18:0)
[06:15:54.451] Build status: STARTED
[06:15:54.451] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:19:0) after 4m4s
[06:19:58.475] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:19:0)
[06:19:58.516] Build status: STARTED
[06:19:58.516] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:20:0) after 4m2s
[06:24:00.534] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:20:0)
[06:24:00.594] Build status: STARTED
[06:24:00.594] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:21:0) after 8m1s
[06:32:01.778] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:21:0)
[06:32:02.043] Build status: STARTED
[06:32:02.043] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:22:0) after 1m53s
[06:33:55.056] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:22:0)
[06:33:55.106] Build status: STARTED
[06:33:55.106] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:23:0) after 2m3s
[06:35:58.118] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:23:0)
[06:35:58.273] Build status: STARTED
[06:35:58.273] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:24:0) after 9m17s
[06:45:15.290] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:24:0)
[06:45:15.310] Build status: STARTED
[06:45:15.310] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:25:0) after 1m29s
[06:46:45.426] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:25:0)
[06:46:45.455] Build status: STARTED
[06:46:45.455] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:26:0) after 4m43s
[06:51:28.725] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:26:0)
[06:51:28.756] Build status: STARTED
[06:51:28.756] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:27:0) after 1m33s
[06:53:02.503] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:27:0)
[06:53:02.529] Build status: STARTED
[06:53:02.529] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:28:0) after 2m32s
[06:55:34.789] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:28:0)
[06:55:34.806] Build status: STARTED
[06:55:34.806] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:29:0) after 9m44s
[07:05:18.826] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:29:0)
[07:05:18.855] Build status: STARTED
[07:05:18.855] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:30:0) after 3m54s
[07:09:13.083] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:30:0)
[07:09:13.171] Build status: STARTED
[07:09:13.171] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:31:0) after 8m21s
[07:17:34.187] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:31:0)
[07:17:34.218] Build status: STARTED
[07:17:34.218] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:32:0) after 9m6s
[07:26:40.243] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:32:0)
[07:26:40.319] Build status: STARTED
[07:26:40.319] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:33:0) after 6m58s
[07:33:38.489] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:33:0)
[07:33:38.536] Build status: STARTED
[07:33:38.536] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:34:0) after 1m8s
[07:34:46.687] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:34:0)
[07:34:46.851] Build status: STARTED
[07:34:46.851] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:35:0) after 1m23s
[07:36:09.866] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:35:0)
[07:36:09.897] Build status: STARTED
[07:36:09.897] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:36:0) after 2m0s
[07:38:09.966] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:36:0)
[07:38:09.999] Build status: STARTED
[07:38:09.999] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:37:0) after 6m20s
[07:44:30.116] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:37:0)
[07:44:30.148] Build status: STARTED
[07:44:30.148] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:38:0) after 8m56s
[07:53:26.251] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:38:0)
[07:53:26.277] Build status: STARTED
[07:53:26.277] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:39:0) after 4m28s
[07:57:54.290] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:39:0)
[07:57:54.320] Build status: STARTED
[07:57:54.320] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:40:0) after 4m10s
[08:02:04.343] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:40:0)
[08:02:04.408] Build status: STARTED
[08:02:04.408] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:41:0) after 5m30s
[08:07:34.424] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:41:0)
[08:07:34.460] Build status: STARTED
[08:07:34.460] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:42:0) after 1m9s
[08:08:43.806] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:42:0)
[08:08:43.831] Build status: STARTED
[08:08:43.831] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:43:0) after 4m30s
[08:13:13.854] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:43:0)
[08:13:13.939] Build status: STARTED
[08:13:13.940] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:44:0) after 2m10s
[08:15:23.958] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:44:0)
[08:15:24.011] Build status: STARTED
[08:15:24.011] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:45:0) after 6m34s
[08:21:58.372] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:45:0)
[08:21:58.406] Build status: STARTED
[08:21:58.407] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:46:0) after 1m16s
[08:23:14.824] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:46:0)
[08:23:14.850] Build status: STARTED
[08:23:14.850] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:47:0) after 1m12s
[08:24:27.186] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:47:0)
[08:24:27.222] Build status: STARTED
[08:24:27.222] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:48:0) after 8m21s
[08:32:48.238] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:48:0)
[08:32:48.272] Build status: STARTED
[08:32:48.272] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:49:0) after 9m37s
[08:42:25.675] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:49:0)
[08:42:25.706] Build status: STARTED
[08:42:25.706] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:50:0) after 3m26s
[08:45:51.758] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:50:0)
[08:45:51.774] Build status: STARTED
[08:45:51.774] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:51:0) after 3m19s
[08:49:10.889] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:51:0)
[08:49:10.926] Build status: STARTED
[08:49:10.926] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:52:0) after 7m25s
[08:56:35.964] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:52:0)
[08:56:35.993] Build status: STARTED
[08:56:35.993] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:53:0) after 7m2s
[09:03:38.029] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:53:0)
[09:03:38.058] Build status: STARTED
[09:03:38.058] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:54:0) after 5m28s
[09:09:06.115] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:54:0)
[09:09:06.163] Build status: STARTED
[09:09:06.163] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:55:0) after 3m47s
[09:12:53.288] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:55:0)
[09:12:53.382] Build status: STARTED
[09:12:53.382] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:56:0) after 7m9s
[09:20:02.680] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:56:0)
[09:20:02.762] Build status: STARTED
[09:20:02.762] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:57:0) after 6m38s
[09:26:40.757] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:57:0)
[09:26:40.787] Build status: STARTED
[09:26:40.787] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:58:0) after 1m35s
[09:28:15.804] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:58:0)
[09:28:15.884] Build status: STARTED
[09:28:15.884] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:59:0) after 2m29s
[09:30:44.901] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:59:0)
[09:30:44.928] Build status: STARTED
[09:30:44.928] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:60:0) after 6m27s
[09:37:11.944] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:60:0)
[09:37:12.035] Build status: STARTED
[09:37:12.035] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:61:0) after 9m21s
[09:46:33.100] Handling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:61:0)
[09:46:33.127] Build status: STARTED
[09:46:33.127] Scheduling timer "check-buildbucket-build-status" (dawn/cts-roller:8925651528433558736:62:0) after 5m6s
[09:50:19.831] Received PubSub notification, asking Buildbucket for the build status
[09:50:19.852] Build:
{
"id": "8760221466551847073",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "cts-roller"
},
"createdBy": "project:dawn",
"createTime": "2023-12-31T05:00:03.526694325Z",
"startTime": "2023-12-31T05:00:04.704403Z",
"endTime": "2023-12-31T09:50:18.866035Z",
"updateTime": "2023-12-31T09:50:18.866035Z",
"status": "SUCCESS",
"input": {
}
}
[09:50:19.852] Invocation finished in 4h50m17.503488828s with status SUCCEEDED