[00:52:26.901] New invocation is queued and will start shortly
[00:52:29.105] Starting the invocation (attempt 1)
[00:52:29.132] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/3003d35d53be38d41a67907b59591edc8aeaf73d"
[00:52:29.132] Popped gitiles commit info from properties and tags
[00:52:29.132] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:52:29.132] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:52:29.133] Buildbucket request:
{
"requestId": "8884898470626563808",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884898470626563808",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5fd1e1fb50cbfc30bd53830954955e1dcdc69029"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5fd1e1fb50cbfc30bd53830954955e1dcdc69029",
"title": "5fd1e1fb50cbfc30bd53830954955e1dcdc69029",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5fd1e1fb50cbfc30bd53830954955e1dcdc69029"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "096b4e762c9307589b5b4938ec7a515d00259c4d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@096b4e762c9307589b5b4938ec7a515d00259c4d",
"title": "096b4e762c9307589b5b4938ec7a515d00259c4d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/096b4e762c9307589b5b4938ec7a515d00259c4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "011a081c795e5cba95528dd4a2b472d26328cc5b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@011a081c795e5cba95528dd4a2b472d26328cc5b",
"title": "011a081c795e5cba95528dd4a2b472d26328cc5b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/011a081c795e5cba95528dd4a2b472d26328cc5b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3ccc4c66a0c4c9aa3a4d9e5336b7df26099aa539"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3ccc4c66a0c4c9aa3a4d9e5336b7df26099aa539",
"title": "3ccc4c66a0c4c9aa3a4d9e5336b7df26099aa539",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3ccc4c66a0c4c9aa3a4d9e5336b7df26099aa539"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c658b93c36eee950092efb05b9dea2343a7b5e86"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c658b93c36eee950092efb05b9dea2343a7b5e86",
"title": "c658b93c36eee950092efb05b9dea2343a7b5e86",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c658b93c36eee950092efb05b9dea2343a7b5e86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3003d35d53be38d41a67907b59591edc8aeaf73d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3003d35d53be38d41a67907b59591edc8aeaf73d",
"title": "3003d35d53be38d41a67907b59591edc8aeaf73d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3003d35d53be38d41a67907b59591edc8aeaf73d"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "3003d35d53be38d41a67907b59591edc8aeaf73d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884898470626563808"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:52:29.531] Scheduled build:
{
"id": "8719468407553482449",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-03-25T00:52:29.208327815Z",
"updateTime": "2025-03-25T00:52:29.208327815Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "3003d35d53be38d41a67907b59591edc8aeaf73d",
"ref": "refs/heads/main"
}
}
}
[00:52:29.531] Task URL: https://cr-buildbucket.appspot.com/build/8719468407553482449
[00:52:29.531] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:2:0) after 7m49s
[00:52:30.540] Received PubSub notification, asking Buildbucket for the build status
[00:52:30.575] Build status: SCHEDULED
[01:00:18.557] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:2:0)
[01:00:18.592] Build status: SCHEDULED
[01:00:18.592] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:4:0) after 1m59s
[01:02:17.625] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:4:0)
[01:02:17.649] Build status: SCHEDULED
[01:02:17.649] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:5:0) after 2m0s
[01:04:17.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:5:0)
[01:04:17.780] Build status: SCHEDULED
[01:04:17.780] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:6:0) after 7m15s
[01:11:32.888] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:6:0)
[01:11:32.914] Build status: SCHEDULED
[01:11:32.914] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:7:0) after 3m51s
[01:15:24.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:7:0)
[01:15:24.054] Build status: SCHEDULED
[01:15:24.054] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:8:0) after 6m37s
[01:22:01.149] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:8:0)
[01:22:01.180] Build status: SCHEDULED
[01:22:01.180] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:9:0) after 5m56s
[01:27:57.291] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:9:0)
[01:27:57.318] Build status: SCHEDULED
[01:27:57.318] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:10:0) after 8m54s
[01:36:51.402] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:10:0)
[01:36:51.423] Build status: SCHEDULED
[01:36:51.423] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:11:0) after 5m46s
[01:42:37.409] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:11:0)
[01:42:37.438] Build status: SCHEDULED
[01:42:37.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:12:0) after 9m5s
[01:51:42.425] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:12:0)
[01:51:42.440] Build status: SCHEDULED
[01:51:42.440] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:13:0) after 6m41s
[01:58:23.486] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:13:0)
[01:58:23.516] Build status: SCHEDULED
[01:58:23.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:14:0) after 4m49s
[02:03:12.537] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:14:0)
[02:03:12.553] Build status: SCHEDULED
[02:03:12.553] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:15:0) after 3m5s
[02:06:17.572] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:15:0)
[02:06:17.594] Build status: SCHEDULED
[02:06:17.594] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:16:0) after 7m58s
[02:14:15.626] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:16:0)
[02:14:15.648] Build status: SCHEDULED
[02:14:15.648] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:17:0) after 8m40s
[02:22:55.676] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:17:0)
[02:22:55.714] Build status: SCHEDULED
[02:22:55.714] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:18:0) after 8m29s
[02:31:24.740] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:18:0)
[02:31:24.758] Build status: SCHEDULED
[02:31:24.758] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:19:0) after 9m41s
[02:41:05.779] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:19:0)
[02:41:05.807] Build status: SCHEDULED
[02:41:05.807] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:20:0) after 5m34s
[02:46:39.822] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:20:0)
[02:46:39.840] Build status: SCHEDULED
[02:46:39.840] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:21:0) after 7m2s
[02:53:41.945] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:21:0)
[02:53:42.042] Build status: SCHEDULED
[02:53:42.042] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:22:0) after 4m4s
[02:57:46.014] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:22:0)
[02:57:46.031] Build status: SCHEDULED
[02:57:46.031] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:23:0) after 2m18s
[03:00:04.027] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:23:0)
[03:00:04.055] Build status: SCHEDULED
[03:00:04.055] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:24:0) after 6m56s
[03:07:00.166] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:24:0)
[03:07:00.186] Build status: SCHEDULED
[03:07:00.186] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:25:0) after 6m32s
[03:13:32.205] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:25:0)
[03:13:32.258] Build status: SCHEDULED
[03:13:32.258] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:26:0) after 7m50s
[03:21:22.291] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:26:0)
[03:21:22.338] Build status: SCHEDULED
[03:21:22.338] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:27:0) after 6m28s
[03:27:50.353] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:27:0)
[03:27:50.379] Build status: SCHEDULED
[03:27:50.379] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:28:0) after 5m16s
[03:33:06.486] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:28:0)
[03:33:06.505] Build status: SCHEDULED
[03:33:06.505] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:29:0) after 1m24s
[03:34:30.520] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:29:0)
[03:34:30.547] Build status: SCHEDULED
[03:34:30.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:30:0) after 2m26s
[03:36:56.568] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:30:0)
[03:36:56.607] Build status: SCHEDULED
[03:36:56.607] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:31:0) after 4m49s
[03:41:45.631] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:31:0)
[03:41:45.660] Build status: SCHEDULED
[03:41:45.660] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:32:0) after 8m49s
[03:50:34.686] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:32:0)
[03:50:34.708] Build status: SCHEDULED
[03:50:34.708] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:33:0) after 9m56s
[04:00:30.766] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:33:0)
[04:00:30.783] Build status: SCHEDULED
[04:00:30.783] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:34:0) after 1m46s
[04:02:16.797] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:34:0)
[04:02:16.852] Build status: SCHEDULED
[04:02:16.852] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:35:0) after 1m4s
[04:03:20.993] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:35:0)
[04:03:21.014] Build status: SCHEDULED
[04:03:21.014] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:36:0) after 1m47s
[04:05:08.004] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:36:0)
[04:05:08.023] Build status: SCHEDULED
[04:05:08.023] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:37:0) after 9m45s
[04:14:53.101] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:37:0)
[04:14:53.119] Build status: SCHEDULED
[04:14:53.119] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:38:0) after 3m7s
[04:18:00.134] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:38:0)
[04:18:00.159] Build status: SCHEDULED
[04:18:00.160] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:39:0) after 5m25s
[04:22:50.915] Received PubSub notification, asking Buildbucket for the build status
[04:22:50.932] Build status: STARTED
[04:23:25.224] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:39:0)
[04:23:25.244] Build status: STARTED
[04:23:25.244] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:41:0) after 3m18s
[04:26:43.311] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:41:0)
[04:26:43.327] Build status: STARTED
[04:26:43.327] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:42:0) after 4m25s
[04:31:08.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:42:0)
[04:31:08.376] Build status: STARTED
[04:31:08.376] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:43:0) after 4m58s
[04:36:06.427] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:43:0)
[04:36:06.444] Build status: STARTED
[04:36:06.444] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:44:0) after 3m46s
[04:39:52.490] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:44:0)
[04:39:52.514] Build status: STARTED
[04:39:52.514] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:45:0) after 8m50s
[04:48:42.537] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:45:0)
[04:48:42.554] Build status: STARTED
[04:48:42.554] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8884898470626563808:46:0) after 9m37s
[04:50:00.459] Received PubSub notification, asking Buildbucket for the build status
[04:50:00.493] Build:
{
"id": "8719468407553482449",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-03-25T00:52:29.208327815Z",
"startTime": "2025-03-25T04:22:50.730041479Z",
"endTime": "2025-03-25T04:50:00.218477027Z",
"updateTime": "2025-03-25T04:50:00.218477027Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "3003d35d53be38d41a67907b59591edc8aeaf73d",
"ref": "refs/heads/main"
}
}
}
[04:50:00.494] Invocation finished in 3h57m33.599961125s with status SUCCEEDED