[01:56:08.335] New invocation is queued and will start shortly
[01:56:09.463] Starting the invocation (attempt 1)
[01:56:09.490] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/2c0b581240be92bda0d6377a0e3a74f800c75406"
[01:56:09.490] Popped gitiles commit info from properties and tags
[01:56:09.490] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:56:09.491] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:56:09.491] Buildbucket request:
{
"requestId": "8868858800517932992",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868858800517932992",
"job": "pigweed/pigweed.dev.ci-envtest-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6ac6638997c01c3054a466c6c4cde8a6adc01a8d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6ac6638997c01c3054a466c6c4cde8a6adc01a8d",
"title": "6ac6638997c01c3054a466c6c4cde8a6adc01a8d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6ac6638997c01c3054a466c6c4cde8a6adc01a8d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a764d26aefecc6922982f95f1d12d1c47abd6a66"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a764d26aefecc6922982f95f1d12d1c47abd6a66",
"title": "a764d26aefecc6922982f95f1d12d1c47abd6a66",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a764d26aefecc6922982f95f1d12d1c47abd6a66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2c0b581240be92bda0d6377a0e3a74f800c75406"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2c0b581240be92bda0d6377a0e3a74f800c75406",
"title": "2c0b581240be92bda0d6377a0e3a74f800c75406",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2c0b581240be92bda0d6377a0e3a74f800c75406"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2c0b581240be92bda0d6377a0e3a74f800c75406",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868858800517932992"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-envtest-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:56:09.903] Scheduled build:
{
"id": "8703428738579468049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-18T01:56:09.552968737Z",
"updateTime": "2025-09-18T01:56:09.552968737Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2c0b581240be92bda0d6377a0e3a74f800c75406",
"ref": "refs/heads/main"
}
}
}
[01:56:09.903] Task URL: https://cr-buildbucket.appspot.com/build/8703428738579468049
[01:56:09.903] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:2:0) after 4m41s
[01:56:10.888] Received PubSub notification, asking Buildbucket for the build status
[01:56:10.912] Build status: SCHEDULED
[02:00:50.919] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:2:0)
[02:00:50.940] Build status: SCHEDULED
[02:00:50.940] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:4:0) after 3m48s
[02:04:38.990] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:4:0)
[02:04:39.012] Build status: SCHEDULED
[02:04:39.012] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:5:0) after 9m18s
[02:13:57.035] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:5:0)
[02:13:57.052] Build status: SCHEDULED
[02:13:57.052] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:6:0) after 9m37s
[02:23:34.065] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:6:0)
[02:23:34.089] Build status: SCHEDULED
[02:23:34.089] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:7:0) after 5m53s
[02:29:27.102] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:7:0)
[02:29:27.140] Build status: SCHEDULED
[02:29:27.140] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:8:0) after 7m15s
[02:36:42.212] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:8:0)
[02:36:42.236] Build status: SCHEDULED
[02:36:42.236] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:9:0) after 7m17s
[02:43:59.249] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:9:0)
[02:43:59.281] Build status: SCHEDULED
[02:43:59.281] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:10:0) after 8m11s
[02:52:10.295] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:10:0)
[02:52:10.320] Build status: SCHEDULED
[02:52:10.320] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:11:0) after 5m16s
[02:57:26.290] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:11:0)
[02:57:26.395] Build status: SCHEDULED
[02:57:26.396] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:12:0) after 6m57s
[03:04:23.483] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:12:0)
[03:04:23.498] Build status: SCHEDULED
[03:04:23.499] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:13:0) after 2m22s
[03:06:45.528] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:13:0)
[03:06:45.547] Build status: SCHEDULED
[03:06:45.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:14:0) after 4m46s
[03:11:31.521] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:14:0)
[03:11:31.536] Build status: SCHEDULED
[03:11:31.536] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:15:0) after 5m25s
[03:16:56.583] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:15:0)
[03:16:56.602] Build status: SCHEDULED
[03:16:56.603] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:16:0) after 2m54s
[03:19:50.601] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:16:0)
[03:19:50.620] Build status: SCHEDULED
[03:19:50.621] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:17:0) after 6m0s
[03:25:50.766] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:17:0)
[03:25:50.860] Build status: SCHEDULED
[03:25:50.860] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:18:0) after 2m32s
[03:28:22.879] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:18:0)
[03:28:22.902] Build status: SCHEDULED
[03:28:22.902] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:19:0) after 4m49s
[03:33:11.911] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:19:0)
[03:33:11.978] Build status: SCHEDULED
[03:33:11.978] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:20:0) after 9m2s
[03:42:13.991] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:20:0)
[03:42:14.019] Build status: SCHEDULED
[03:42:14.019] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:21:0) after 7m0s
[03:49:14.042] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:21:0)
[03:49:14.074] Build status: SCHEDULED
[03:49:14.074] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:22:0) after 8m16s
[03:57:30.090] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:22:0)
[03:57:30.117] Build status: SCHEDULED
[03:57:30.117] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:23:0) after 2m42s
[04:00:12.136] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:23:0)
[04:00:12.171] Build status: SCHEDULED
[04:00:12.172] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:24:0) after 4m12s
[04:04:24.210] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:24:0)
[04:04:24.245] Build status: SCHEDULED
[04:04:24.245] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:25:0) after 3m30s
[04:07:54.316] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:25:0)
[04:07:54.396] Build status: SCHEDULED
[04:07:54.396] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:26:0) after 6m19s
[04:14:13.412] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:26:0)
[04:14:13.429] Build status: SCHEDULED
[04:14:13.429] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:27:0) after 6m33s
[04:20:46.476] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:27:0)
[04:20:46.500] Build status: SCHEDULED
[04:20:46.500] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:28:0) after 4m56s
[04:25:42.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:28:0)
[04:25:42.585] Build status: SCHEDULED
[04:25:42.585] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:29:0) after 2m1s
[04:27:43.718] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:29:0)
[04:27:43.764] Build status: SCHEDULED
[04:27:43.764] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:30:0) after 5m39s
[04:33:22.878] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:30:0)
[04:33:22.894] Build status: SCHEDULED
[04:33:22.894] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:31:0) after 6m59s
[04:40:21.917] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:31:0)
[04:40:21.936] Build status: SCHEDULED
[04:40:21.936] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:32:0) after 1m8s
[04:41:29.952] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:32:0)
[04:41:29.976] Build status: SCHEDULED
[04:41:29.976] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:33:0) after 6m7s
[04:47:37.041] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:33:0)
[04:47:37.060] Build status: SCHEDULED
[04:47:37.060] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:34:0) after 9m33s
[04:57:10.075] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:34:0)
[04:57:10.099] Build status: SCHEDULED
[04:57:10.099] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:35:0) after 1m51s
[04:59:01.083] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:35:0)
[04:59:01.098] Build status: SCHEDULED
[04:59:01.098] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:36:0) after 8m5s
[05:07:06.153] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:36:0)
[05:07:06.190] Build status: SCHEDULED
[05:07:06.190] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:37:0) after 8m59s
[05:13:54.541] Received PubSub notification, asking Buildbucket for the build status
[05:13:54.558] Build status: STARTED
[05:16:05.246] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:37:0)
[05:16:05.261] Build status: STARTED
[05:16:05.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8868858800517932992:39:0) after 2m55s
[05:18:43.609] Received PubSub notification, asking Buildbucket for the build status
[05:18:43.635] Build:
{
"id": "8703428738579468049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-18T01:56:09.552968737Z",
"startTime": "2025-09-18T05:13:54.340505511Z",
"endTime": "2025-09-18T05:18:37.022157323Z",
"updateTime": "2025-09-18T05:18:37.022157323Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2c0b581240be92bda0d6377a0e3a74f800c75406",
"ref": "refs/heads/main"
}
}
}
[05:18:43.635] Invocation finished in 3h22m35.314944454s with status SUCCEEDED