[17:07:38.111] New invocation is queued and will start shortly
[17:07:39.240] Starting the invocation (attempt 1)
[17:07:39.270] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/67a7bac43b4d48157871540e8bbcb4f45dfb7f48"
[17:07:39.270] Popped gitiles commit info from properties and tags
[17:07:39.270] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:07:39.270] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:07:39.270] Buildbucket request:
{
"requestId": "8873331302450220128",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-crypto-mbedtls"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873331302450220128",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "40e21b586b989e8a6336d0adf0fa047bccea156f"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@40e21b586b989e8a6336d0adf0fa047bccea156f",
"title": "40e21b586b989e8a6336d0adf0fa047bccea156f",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/40e21b586b989e8a6336d0adf0fa047bccea156f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "963ed52a5af9e4cf135cf16ba40a19669cabfbc1"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@963ed52a5af9e4cf135cf16ba40a19669cabfbc1",
"title": "963ed52a5af9e4cf135cf16ba40a19669cabfbc1",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/963ed52a5af9e4cf135cf16ba40a19669cabfbc1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "67a7bac43b4d48157871540e8bbcb4f45dfb7f48"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@67a7bac43b4d48157871540e8bbcb4f45dfb7f48",
"title": "67a7bac43b4d48157871540e8bbcb4f45dfb7f48",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/67a7bac43b4d48157871540e8bbcb4f45dfb7f48"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "67a7bac43b4d48157871540e8bbcb4f45dfb7f48",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873331302450220128"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:07:39.626] Scheduled build:
{
"id": "8707901240506836001",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-crypto-mbedtls"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-30T17:07:39.334007656Z",
"updateTime": "2025-07-30T17:07:39.334007656Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "67a7bac43b4d48157871540e8bbcb4f45dfb7f48",
"ref": "refs/heads/main"
}
}
}
[17:07:39.626] Task URL: https://cr-buildbucket.appspot.com/build/8707901240506836001
[17:07:39.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:2:0) after 2m45s
[17:07:40.297] Received PubSub notification, asking Buildbucket for the build status
[17:07:40.320] Build status: SCHEDULED
[17:10:24.690] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:2:0)
[17:10:24.711] Build status: SCHEDULED
[17:10:24.711] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:4:0) after 7m53s
[17:18:17.692] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:4:0)
[17:18:17.711] Build status: SCHEDULED
[17:18:17.711] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:5:0) after 2m43s
[17:21:00.783] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:5:0)
[17:21:00.813] Build status: SCHEDULED
[17:21:00.813] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:6:0) after 5m24s
[17:26:24.898] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:6:0)
[17:26:24.928] Build status: SCHEDULED
[17:26:24.928] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:7:0) after 8m20s
[17:34:45.058] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:7:0)
[17:34:45.090] Build status: SCHEDULED
[17:34:45.090] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:8:0) after 8m1s
[17:42:46.114] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:8:0)
[17:42:46.205] Build status: SCHEDULED
[17:42:46.205] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:9:0) after 3m14s
[17:46:00.276] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:9:0)
[17:46:00.298] Build status: SCHEDULED
[17:46:00.298] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:10:0) after 5m1s
[17:51:01.363] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:10:0)
[17:51:01.387] Build status: SCHEDULED
[17:51:01.387] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:11:0) after 8m32s
[17:59:33.456] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:11:0)
[17:59:33.474] Build status: SCHEDULED
[17:59:33.474] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:12:0) after 2m38s
[18:02:11.487] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:12:0)
[18:02:11.550] Build status: SCHEDULED
[18:02:11.550] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:13:0) after 4m34s
[18:06:45.689] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:13:0)
[18:06:45.714] Build status: SCHEDULED
[18:06:45.714] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:14:0) after 2m32s
[18:09:17.732] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:14:0)
[18:09:17.781] Build status: SCHEDULED
[18:09:17.781] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:15:0) after 5m13s
[18:14:30.794] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:15:0)
[18:14:30.818] Build status: SCHEDULED
[18:14:30.819] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:16:0) after 2m59s
[18:17:29.892] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:16:0)
[18:17:29.915] Build status: SCHEDULED
[18:17:29.915] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:17:0) after 8m58s
[18:26:27.935] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:17:0)
[18:26:27.962] Build status: SCHEDULED
[18:26:27.962] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:18:0) after 8m18s
[18:34:46.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:18:0)
[18:34:46.047] Build status: SCHEDULED
[18:34:46.047] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:19:0) after 9m8s
[18:43:54.135] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:19:0)
[18:43:54.155] Build status: SCHEDULED
[18:43:54.155] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:20:0) after 4m5s
[18:47:59.184] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:20:0)
[18:47:59.201] Build status: SCHEDULED
[18:47:59.201] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:21:0) after 1m52s
[18:49:51.425] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:21:0)
[18:49:51.445] Build status: SCHEDULED
[18:49:51.445] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:22:0) after 8m34s
[18:58:25.627] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:22:0)
[18:58:25.643] Build status: SCHEDULED
[18:58:25.644] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:23:0) after 4m16s
[19:02:41.663] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:23:0)
[19:02:41.679] Build status: SCHEDULED
[19:02:41.679] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:24:0) after 6m27s
[19:09:08.749] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:24:0)
[19:09:08.767] Build status: SCHEDULED
[19:09:08.767] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:25:0) after 9m52s
[19:19:00.808] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:25:0)
[19:19:00.828] Build status: SCHEDULED
[19:19:00.828] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:26:0) after 4m47s
[19:23:47.865] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:26:0)
[19:23:47.879] Build status: SCHEDULED
[19:23:47.880] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:27:0) after 4m48s
[19:28:35.909] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:27:0)
[19:28:35.926] Build status: SCHEDULED
[19:28:35.926] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:28:0) after 3m13s
[19:31:48.985] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:28:0)
[19:31:49.002] Build status: SCHEDULED
[19:31:49.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:29:0) after 2m19s
[19:34:08.110] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:29:0)
[19:34:08.134] Build status: SCHEDULED
[19:34:08.134] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:30:0) after 1m23s
[19:34:35.040] Received PubSub notification, asking Buildbucket for the build status
[19:34:35.059] Build status: STARTED
[19:35:31.248] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:30:0)
[19:35:31.265] Build status: STARTED
[19:35:31.265] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:32:0) after 4m56s
[19:40:27.399] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:32:0)
[19:40:27.415] Build status: STARTED
[19:40:27.415] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-crypto-mbedtls:8873331302450220128:33:0) after 8m8s
[19:47:05.903] Received PubSub notification, asking Buildbucket for the build status
[19:47:05.925] Build:
{
"id": "8707901240506836001",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-crypto-mbedtls"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-30T17:07:39.334007656Z",
"startTime": "2025-07-30T19:34:14.696593194Z",
"endTime": "2025-07-30T19:47:05.686660970Z",
"updateTime": "2025-07-30T19:47:05.686660970Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "67a7bac43b4d48157871540e8bbcb4f45dfb7f48",
"ref": "refs/heads/main"
}
}
}
[19:47:05.925] Invocation finished in 2h39m27.827819665s with status SUCCEEDED