[07:38:52.867] New invocation is queued and will start shortly
[07:38:54.663] Starting the invocation (attempt 1)
[07:38:54.690] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/bc069d28b06bdad4cb0f78a009f53c0214dc759f"
[07:38:54.690] Popped gitiles commit info from properties and tags
[07:38:54.690] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:38:54.690] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:38:54.690] Buildbucket request:
{
"requestId": "8891667672565771408",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891667672565771408",
"job": "fuchsia/fuchsia-coverage-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "23101af0d39ee01d4ad2db5c9b94a2a795a45b8f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@23101af0d39ee01d4ad2db5c9b94a2a795a45b8f",
"title": "23101af0d39ee01d4ad2db5c9b94a2a795a45b8f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/23101af0d39ee01d4ad2db5c9b94a2a795a45b8f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "33f2e2afdc99b92538990cce35ed513965195b30"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@33f2e2afdc99b92538990cce35ed513965195b30",
"title": "33f2e2afdc99b92538990cce35ed513965195b30",
"url": "https://fuchsia.googlesource.com/fuchsia/+/33f2e2afdc99b92538990cce35ed513965195b30"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bc069d28b06bdad4cb0f78a009f53c0214dc759f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bc069d28b06bdad4cb0f78a009f53c0214dc759f",
"title": "bc069d28b06bdad4cb0f78a009f53c0214dc759f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bc069d28b06bdad4cb0f78a009f53c0214dc759f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bc069d28b06bdad4cb0f78a009f53c0214dc759f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891667672565771408"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-coverage-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:38:55.137] Scheduled build:
{
"id": "8726237609928560177",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-09T07:38:54.760702471Z",
"updateTime": "2025-01-09T07:38:54.760702471Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bc069d28b06bdad4cb0f78a009f53c0214dc759f",
"ref": "refs/heads/main"
}
}
}
[07:38:55.137] Task URL: https://cr-buildbucket.appspot.com/build/8726237609928560177
[07:38:55.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:2:0) after 2m33s
[07:39:03.394] Received PubSub notification, asking Buildbucket for the build status
[07:39:03.416] Build status: SCHEDULED
[07:39:18.860] Received PubSub notification, asking Buildbucket for the build status
[07:39:18.977] Build status: STARTED
[07:41:28.151] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:2:0)
[07:41:28.177] Build status: STARTED
[07:41:28.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:5:0) after 9m25s
[07:50:53.212] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:5:0)
[07:50:53.239] Build status: STARTED
[07:50:53.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:6:0) after 9m47s
[08:00:40.254] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:6:0)
[08:00:40.282] Build status: STARTED
[08:00:40.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:7:0) after 7m47s
[08:08:27.306] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:7:0)
[08:08:27.327] Build status: STARTED
[08:08:27.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:8:0) after 9m16s
[08:17:43.350] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:8:0)
[08:17:43.387] Build status: STARTED
[08:17:43.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:9:0) after 8m15s
[08:25:58.410] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:9:0)
[08:25:58.440] Build status: STARTED
[08:25:58.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:10:0) after 7m24s
[08:33:22.462] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:10:0)
[08:33:22.488] Build status: STARTED
[08:33:22.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:11:0) after 4m12s
[08:37:35.173] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:11:0)
[08:37:35.197] Build status: STARTED
[08:37:35.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:12:0) after 1m22s
[08:38:57.504] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:12:0)
[08:38:57.532] Build status: STARTED
[08:38:57.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:13:0) after 6m51s
[08:45:48.792] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:13:0)
[08:45:48.826] Build status: STARTED
[08:45:48.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:14:0) after 5m1s
[08:50:50.422] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:14:0)
[08:50:50.440] Build status: STARTED
[08:50:50.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:15:0) after 8m13s
[08:59:03.510] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:15:0)
[08:59:03.576] Build status: STARTED
[08:59:03.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:16:0) after 5m58s
[09:05:01.601] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:16:0)
[09:05:01.623] Build status: STARTED
[09:05:01.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:17:0) after 2m48s
[09:07:49.740] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:17:0)
[09:07:49.760] Build status: STARTED
[09:07:49.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:18:0) after 1m31s
[09:09:20.785] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:18:0)
[09:09:20.808] Build status: STARTED
[09:09:20.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:19:0) after 6m13s
[09:15:33.802] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:19:0)
[09:15:33.827] Build status: STARTED
[09:15:33.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:20:0) after 8m4s
[09:23:37.847] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:20:0)
[09:23:37.871] Build status: STARTED
[09:23:37.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:21:0) after 6m49s
[09:30:26.910] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:21:0)
[09:30:26.931] Build status: STARTED
[09:30:26.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:22:0) after 3m55s
[09:34:21.932] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:22:0)
[09:34:21.956] Build status: STARTED
[09:34:21.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:23:0) after 4m4s
[09:38:25.975] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:23:0)
[09:38:25.993] Build status: STARTED
[09:38:25.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:24:0) after 1m46s
[09:40:11.987] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:24:0)
[09:40:12.015] Build status: STARTED
[09:40:12.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:25:0) after 9m39s
[09:49:51.063] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:25:0)
[09:49:51.087] Build status: STARTED
[09:49:51.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:26:0) after 9m29s
[09:59:20.118] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:26:0)
[09:59:20.145] Build status: STARTED
[09:59:20.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:27:0) after 5m55s
[10:05:15.163] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:27:0)
[10:05:15.184] Build status: STARTED
[10:05:15.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:28:0) after 3m37s
[10:08:52.285] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:28:0)
[10:08:52.306] Build status: STARTED
[10:08:52.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:29:0) after 3m22s
[10:12:14.308] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:29:0)
[10:12:14.332] Build status: STARTED
[10:12:14.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:30:0) after 1m19s
[10:13:33.412] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:30:0)
[10:13:33.429] Build status: STARTED
[10:13:33.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:31:0) after 4m40s
[10:18:13.479] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:31:0)
[10:18:13.498] Build status: STARTED
[10:18:13.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:32:0) after 2m13s
[10:20:26.525] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:32:0)
[10:20:26.706] Build status: STARTED
[10:20:26.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:33:0) after 2m11s
[10:22:37.710] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:33:0)
[10:22:37.728] Build status: STARTED
[10:22:37.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:34:0) after 4m38s
[10:27:15.724] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:34:0)
[10:27:15.752] Build status: STARTED
[10:27:15.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:35:0) after 1m23s
[10:28:38.993] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:35:0)
[10:28:39.098] Build status: STARTED
[10:28:39.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:36:0) after 8m34s
[10:37:13.602] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:36:0)
[10:37:13.619] Build status: STARTED
[10:37:13.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:37:0) after 9m29s
[10:46:42.735] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:37:0)
[10:46:42.791] Build status: STARTED
[10:46:42.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:38:0) after 2m35s
[10:49:17.813] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:38:0)
[10:49:17.829] Build status: STARTED
[10:49:17.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:39:0) after 3m49s
[10:53:06.954] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:39:0)
[10:53:06.978] Build status: STARTED
[10:53:06.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8891667672565771408:40:0) after 2m11s
[10:54:10.026] Received PubSub notification, asking Buildbucket for the build status
[10:54:10.089] Build:
{
"id": "8726237609928560177",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-09T07:38:54.760702471Z",
"startTime": "2025-01-09T07:39:07.910687181Z",
"endTime": "2025-01-09T10:54:09.560967346Z",
"updateTime": "2025-01-09T10:54:09.560967346Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bc069d28b06bdad4cb0f78a009f53c0214dc759f",
"ref": "refs/heads/main"
}
}
}
[10:54:10.089] Invocation finished in 3h15m17.226917591s with status SUCCEEDED