[15:47:18.449] New invocation is queued and will start shortly
[15:47:22.016] Starting the invocation (attempt 1)
[15:47:22.050] Ignoring gitiles_ref tag without the buildset tag
[15:47:22.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:47:22.050] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:47:22.050] Buildbucket request:
{
"requestId": "8903595743006123568",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "chromium-lkgr-finder"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8903595743006123568",
"job": "infra/chromium-lkgr-finder",
"triggers": [
{
"cron": {
"generation": "128997"
},
"id": "cron:v1:128997"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903595743006123568"
},
{
"key": "scheduler_job_id",
"value": "infra/chromium-lkgr-finder"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:47:22.469] Scheduled build:
{
"id": "8738165678500885233",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "chromium-lkgr-finder"
},
"createdBy": "project:infra",
"createTime": "2024-08-30T15:47:22.119152306Z",
"updateTime": "2024-08-30T15:47:22.119152306Z",
"status": "SCHEDULED",
"input": {
}
}
[15:47:22.469] Task URL: https://cr-buildbucket.appspot.com/build/8738165678500885233
[15:47:22.469] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:2:0) after 3m23s
[15:47:23.493] Received PubSub notification, asking Buildbucket for the build status
[15:47:23.529] Build status: SCHEDULED
[15:50:46.348] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:2:0)
[15:50:46.367] Build status: SCHEDULED
[15:50:46.367] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:4:0) after 8m10s
[15:56:02.394] Received PubSub notification, asking Buildbucket for the build status
[15:56:02.449] Build status: STARTED
[15:58:56.430] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:4:0)
[15:58:56.449] Build status: STARTED
[15:58:56.449] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:6:0) after 2m40s
[16:01:36.465] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:6:0)
[16:01:36.479] Build status: STARTED
[16:01:36.479] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:7:0) after 5m50s
[16:07:26.455] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:7:0)
[16:07:26.527] Build status: STARTED
[16:07:26.527] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:8:0) after 9m55s
[16:17:21.554] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:8:0)
[16:17:21.578] Build status: STARTED
[16:17:21.578] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:9:0) after 6m20s
[16:23:41.639] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:9:0)
[16:23:41.656] Build status: STARTED
[16:23:41.656] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:10:0) after 9m19s
[16:33:00.677] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:10:0)
[16:33:00.694] Build status: STARTED
[16:33:00.694] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:11:0) after 4m45s
[16:37:45.708] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:11:0)
[16:37:45.728] Build status: STARTED
[16:37:45.728] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:12:0) after 4m23s
[16:42:08.791] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:12:0)
[16:42:08.877] Build status: STARTED
[16:42:08.877] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:13:0) after 2m36s
[16:44:44.903] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:13:0)
[16:44:44.944] Build status: STARTED
[16:44:44.944] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:14:0) after 9m57s
[16:54:41.963] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:14:0)
[16:54:42.017] Build status: STARTED
[16:54:42.017] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:15:0) after 4m2s
[16:58:44.031] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:15:0)
[16:58:44.070] Build status: STARTED
[16:58:44.070] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:16:0) after 7m48s
[17:06:32.125] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:16:0)
[17:06:32.160] Build status: STARTED
[17:06:32.160] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:17:0) after 4m18s
[17:10:50.177] Handling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:17:0)
[17:10:50.192] Build status: STARTED
[17:10:50.192] Scheduling timer "check-buildbucket-build-status" (infra/chromium-lkgr-finder:8903595743006123568:18:0) after 9m7s
[17:17:35.171] Received PubSub notification, asking Buildbucket for the build status
[17:17:35.239] Build:
{
"id": "8738165678500885233",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "chromium-lkgr-finder"
},
"createdBy": "project:infra",
"createTime": "2024-08-30T15:47:22.119152306Z",
"startTime": "2024-08-30T15:56:01.906699519Z",
"endTime": "2024-08-30T17:17:34.883951218Z",
"updateTime": "2024-08-30T17:17:34.883951218Z",
"status": "SUCCESS",
"input": {
}
}
[17:17:35.239] Invocation finished in 1h30m16.800416104s with status SUCCEEDED