[22:03:56.562] New invocation is queued and will start shortly
[22:03:57.724] Starting the invocation (attempt 1)
[22:03:57.755] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/23cc90c00be0e6f8c3eef0adfe1a0906fa77b346"
[22:03:57.755] Popped gitiles commit info from properties and tags
[22:03:57.755] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:03:57.755] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:03:57.756] Buildbucket request:
{
"requestId": "8894693544454345168",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894693544454345168",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "99944ede9942532e5a12996575497ecb31a10442"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@99944ede9942532e5a12996575497ecb31a10442",
"title": "99944ede9942532e5a12996575497ecb31a10442",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/99944ede9942532e5a12996575497ecb31a10442"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e85884825ea35e9b3b6f86ab0d12d1a1c5e3518d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e85884825ea35e9b3b6f86ab0d12d1a1c5e3518d",
"title": "e85884825ea35e9b3b6f86ab0d12d1a1c5e3518d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e85884825ea35e9b3b6f86ab0d12d1a1c5e3518d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b336566abf4d6d1119f5373dc534e1f1e1070523"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b336566abf4d6d1119f5373dc534e1f1e1070523",
"title": "b336566abf4d6d1119f5373dc534e1f1e1070523",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b336566abf4d6d1119f5373dc534e1f1e1070523"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4954f2e3f5f44af902081b43ec46c7b70fb99e01"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4954f2e3f5f44af902081b43ec46c7b70fb99e01",
"title": "4954f2e3f5f44af902081b43ec46c7b70fb99e01",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4954f2e3f5f44af902081b43ec46c7b70fb99e01"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "70e716fc3bb6159947423078ea7ba56f33649a6c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@70e716fc3bb6159947423078ea7ba56f33649a6c",
"title": "70e716fc3bb6159947423078ea7ba56f33649a6c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/70e716fc3bb6159947423078ea7ba56f33649a6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "92c01bfdecac8898f14fdc902c6535d612ab1c45"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@92c01bfdecac8898f14fdc902c6535d612ab1c45",
"title": "92c01bfdecac8898f14fdc902c6535d612ab1c45",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/92c01bfdecac8898f14fdc902c6535d612ab1c45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "23cc90c00be0e6f8c3eef0adfe1a0906fa77b346"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@23cc90c00be0e6f8c3eef0adfe1a0906fa77b346",
"title": "23cc90c00be0e6f8c3eef0adfe1a0906fa77b346",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/23cc90c00be0e6f8c3eef0adfe1a0906fa77b346"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "23cc90c00be0e6f8c3eef0adfe1a0906fa77b346",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894693544454345168"
},
{
"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"
}
}
[22:03:58.351] Scheduled build:
{
"id": "8729263482482953729",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-06T22:03:57.821881358Z",
"updateTime": "2024-12-06T22:03:57.821881358Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "23cc90c00be0e6f8c3eef0adfe1a0906fa77b346",
"ref": "refs/heads/main"
}
}
}
[22:03:58.351] Task URL: https://cr-buildbucket.appspot.com/build/8729263482482953729
[22:03:58.351] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:2:0) after 7m37s
[22:03:59.421] Received PubSub notification, asking Buildbucket for the build status
[22:03:59.436] Build status: SCHEDULED
[22:11:35.438] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:2:0)
[22:11:35.471] Build status: SCHEDULED
[22:11:35.471] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:4:0) after 3m46s
[22:15:21.491] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:4:0)
[22:15:21.522] Build status: SCHEDULED
[22:15:21.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:5:0) after 5m44s
[22:21:05.537] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:5:0)
[22:21:05.583] Build status: SCHEDULED
[22:21:05.583] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:6:0) after 7m8s
[22:28:13.732] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:6:0)
[22:28:13.753] Build status: SCHEDULED
[22:28:13.753] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:7:0) after 3m22s
[22:31:35.767] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:7:0)
[22:31:35.794] Build status: SCHEDULED
[22:31:35.794] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:8:0) after 6m36s
[22:38:11.858] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:8:0)
[22:38:11.884] Build status: SCHEDULED
[22:38:11.884] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:9:0) after 5m54s
[22:44:05.916] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:9:0)
[22:44:05.951] Build status: SCHEDULED
[22:44:05.951] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:10:0) after 5m34s
[22:49:39.971] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:10:0)
[22:49:40.000] Build status: SCHEDULED
[22:49:40.000] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:11:0) after 9m44s
[22:59:23.987] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:11:0)
[22:59:24.009] Build status: SCHEDULED
[22:59:24.009] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:12:0) after 1m43s
[23:01:07.083] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:12:0)
[23:01:07.167] Build status: SCHEDULED
[23:01:07.167] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:13:0) after 6m7s
[23:07:14.197] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:13:0)
[23:07:14.224] Build status: SCHEDULED
[23:07:14.224] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:14:0) after 5m21s
[23:12:35.238] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:14:0)
[23:12:35.258] Build status: SCHEDULED
[23:12:35.258] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:15:0) after 8m27s
[23:21:02.276] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:15:0)
[23:21:02.303] Build status: SCHEDULED
[23:21:02.303] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:16:0) after 5m10s
[23:26:12.320] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:16:0)
[23:26:12.364] Build status: SCHEDULED
[23:26:12.364] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:17:0) after 3m41s
[23:29:53.469] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:17:0)
[23:29:53.487] Build status: SCHEDULED
[23:29:53.487] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:18:0) after 2m19s
[23:32:12.669] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:18:0)
[23:32:12.690] Build status: SCHEDULED
[23:32:12.690] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:19:0) after 3m52s
[23:36:04.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:19:0)
[23:36:04.759] Build status: SCHEDULED
[23:36:04.759] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:20:0) after 1m57s
[23:38:01.781] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:20:0)
[23:38:01.814] Build status: SCHEDULED
[23:38:01.814] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:21:0) after 9m14s
[23:47:15.889] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:21:0)
[23:47:15.932] Build status: SCHEDULED
[23:47:15.932] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:22:0) after 1m39s
[23:48:54.961] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:22:0)
[23:48:54.982] Build status: SCHEDULED
[23:48:54.982] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:23:0) after 7m25s
[23:56:20.067] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:23:0)
[23:56:20.086] Build status: SCHEDULED
[23:56:20.086] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:24:0) after 5m50s
[00:02:10.108] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:24:0)
[00:02:10.187] Build status: SCHEDULED
[00:02:10.187] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:25:0) after 9m38s
[00:11:48.211] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:25:0)
[00:11:48.319] Build status: SCHEDULED
[00:11:48.319] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:26:0) after 1m6s
[00:12:54.345] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:26:0)
[00:12:54.363] Build status: SCHEDULED
[00:12:54.363] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:27:0) after 7m5s
[00:19:59.379] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:27:0)
[00:19:59.434] Build status: SCHEDULED
[00:19:59.435] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:28:0) after 9m22s
[00:29:21.452] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:28:0)
[00:29:21.472] Build status: SCHEDULED
[00:29:21.472] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:29:0) after 8m20s
[00:37:41.493] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:29:0)
[00:37:41.509] Build status: SCHEDULED
[00:37:41.509] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:30:0) after 4m17s
[00:41:58.605] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:30:0)
[00:41:58.626] Build status: SCHEDULED
[00:41:58.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:31:0) after 9m41s
[00:46:11.083] Received PubSub notification, asking Buildbucket for the build status
[00:46:11.104] Build status: STARTED
[00:51:39.643] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:31:0)
[00:51:39.673] Build status: STARTED
[00:51:39.673] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:33:0) after 3m34s
[00:55:13.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:33:0)
[00:55:13.757] Build status: STARTED
[00:55:13.757] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:34:0) after 9m28s
[01:04:41.780] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:34:0)
[01:04:41.800] Build status: STARTED
[01:04:41.800] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8894693544454345168:35:0) after 9m43s
[01:05:11.280] Received PubSub notification, asking Buildbucket for the build status
[01:05:11.300] Build:
{
"id": "8729263482482953729",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-06T22:03:57.821881358Z",
"startTime": "2024-12-07T00:46:10.873577859Z",
"endTime": "2024-12-07T01:05:10.668604173Z",
"updateTime": "2024-12-07T01:05:10.668604173Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "23cc90c00be0e6f8c3eef0adfe1a0906fa77b346",
"ref": "refs/heads/main"
}
}
}
[01:05:11.300] Invocation finished in 3h1m14.742650203s with status SUCCEEDED