[16:35:41.117] New invocation is queued and will start shortly
[16:35:42.292] Starting the invocation (attempt 1)
[16:35:42.322] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a"
[16:35:42.322] Popped gitiles commit info from properties and tags
[16:35:42.322] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:35:42.322] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:35:42.323] Buildbucket request:
{
"requestId": "8890909124036596896",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8890909124036596896",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a",
"title": "2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890909124036596896"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:35:42.677] Scheduled build:
{
"id": "8725479062057972465",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-17T16:35:42.382668659Z",
"updateTime": "2025-01-17T16:35:42.382668659Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a",
"ref": "refs/heads/main"
}
}
}
[16:35:42.677] Task URL: https://cr-buildbucket.appspot.com/build/8725479062057972465
[16:35:42.677] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:2:0) after 5m44s
[16:35:43.665] Received PubSub notification, asking Buildbucket for the build status
[16:35:43.694] Build status: SCHEDULED
[16:41:26.743] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:2:0)
[16:41:26.803] Build status: SCHEDULED
[16:41:26.803] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:4:0) after 8m31s
[16:49:57.898] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:4:0)
[16:49:57.973] Build status: SCHEDULED
[16:49:57.973] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:5:0) after 8m23s
[16:58:21.119] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:5:0)
[16:58:21.226] Build status: SCHEDULED
[16:58:21.226] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:6:0) after 9m10s
[17:07:31.293] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:6:0)
[17:07:31.322] Build status: SCHEDULED
[17:07:31.322] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:7:0) after 6m40s
[17:14:11.357] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:7:0)
[17:14:11.433] Build status: SCHEDULED
[17:14:11.433] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:8:0) after 5m25s
[17:19:36.514] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:8:0)
[17:19:36.540] Build status: SCHEDULED
[17:19:36.540] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:9:0) after 2m3s
[17:21:39.630] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:9:0)
[17:21:39.670] Build status: SCHEDULED
[17:21:39.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:10:0) after 2m29s
[17:24:08.773] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:10:0)
[17:24:08.799] Build status: SCHEDULED
[17:24:08.799] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:11:0) after 3m23s
[17:27:31.827] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:11:0)
[17:27:31.858] Build status: SCHEDULED
[17:27:31.858] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:12:0) after 5m9s
[17:32:40.976] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:12:0)
[17:32:40.992] Build status: SCHEDULED
[17:32:40.992] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:13:0) after 5m5s
[17:37:46.055] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:13:0)
[17:37:46.077] Build status: SCHEDULED
[17:37:46.077] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:14:0) after 7m6s
[17:44:52.176] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:14:0)
[17:44:52.208] Build status: SCHEDULED
[17:44:52.208] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:15:0) after 1m6s
[17:45:58.380] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:15:0)
[17:45:58.408] Build status: SCHEDULED
[17:45:58.408] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:16:0) after 4m13s
[17:50:11.428] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:16:0)
[17:50:11.491] Build status: SCHEDULED
[17:50:11.491] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:17:0) after 5m48s
[17:55:59.596] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:17:0)
[17:55:59.627] Build status: SCHEDULED
[17:55:59.627] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:18:0) after 4m40s
[18:00:39.666] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:18:0)
[18:00:39.685] Build status: SCHEDULED
[18:00:39.685] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:19:0) after 1m22s
[18:02:01.683] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:19:0)
[18:02:01.731] Build status: SCHEDULED
[18:02:01.731] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:20:0) after 3m34s
[18:05:35.764] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:20:0)
[18:05:35.782] Build status: SCHEDULED
[18:05:35.782] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:21:0) after 5m9s
[18:10:44.901] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:21:0)
[18:10:44.921] Build status: SCHEDULED
[18:10:44.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:22:0) after 1m10s
[18:11:54.994] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:22:0)
[18:11:55.013] Build status: SCHEDULED
[18:11:55.013] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:23:0) after 7m4s
[18:18:59.017] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:23:0)
[18:18:59.077] Build status: SCHEDULED
[18:18:59.077] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:24:0) after 2m41s
[18:21:40.098] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:24:0)
[18:21:40.115] Build status: SCHEDULED
[18:21:40.115] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:25:0) after 8m19s
[18:29:59.133] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:25:0)
[18:29:59.152] Build status: SCHEDULED
[18:29:59.152] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:26:0) after 9m39s
[18:34:29.232] Received PubSub notification, asking Buildbucket for the build status
[18:34:29.270] Build status: STARTED
[18:39:38.179] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:26:0)
[18:39:38.195] Build status: STARTED
[18:39:38.195] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:28:0) after 7m0s
[18:46:38.213] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:28:0)
[18:46:38.237] Build status: STARTED
[18:46:38.237] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8890909124036596896:29:0) after 8m41s
[18:54:16.202] Received PubSub notification, asking Buildbucket for the build status
[18:54:16.234] Build:
{
"id": "8725479062057972465",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-17T16:35:42.382668659Z",
"startTime": "2025-01-17T18:34:28.959502358Z",
"endTime": "2025-01-17T18:54:15.828262388Z",
"updateTime": "2025-01-17T18:54:15.828262388Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2fa4c63fdecac2d03c7377a7f38d5e5363b6a15a",
"ref": "refs/heads/main"
}
}
}
[18:54:16.234] Invocation finished in 2h18m35.121342588s with status SUCCEEDED