[00:35:12.843] New invocation is queued and will start shortly
[00:35:14.847] Starting the invocation (attempt 1)
[00:35:14.870] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4a1180f48449bd603ace45163febb6226d4272b5"
[00:35:14.870] Popped gitiles commit info from properties and tags
[00:35:14.870] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:35:14.870] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:35:14.871] Buildbucket request:
{
"requestId": "8884084182222338336",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884084182222338336",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e07806d58ac128094d99b96d0e80814f025f84b2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e07806d58ac128094d99b96d0e80814f025f84b2",
"title": "e07806d58ac128094d99b96d0e80814f025f84b2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e07806d58ac128094d99b96d0e80814f025f84b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4a1180f48449bd603ace45163febb6226d4272b5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4a1180f48449bd603ace45163febb6226d4272b5",
"title": "4a1180f48449bd603ace45163febb6226d4272b5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4a1180f48449bd603ace45163febb6226d4272b5"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a1180f48449bd603ace45163febb6226d4272b5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884084182222338336"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:35:15.321] Scheduled build:
{
"id": "8718654119377168929",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-03T00:35:14.928997860Z",
"updateTime": "2025-04-03T00:35:14.928997860Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a1180f48449bd603ace45163febb6226d4272b5",
"ref": "refs/heads/main"
}
}
}
[00:35:15.321] Task URL: https://cr-buildbucket.appspot.com/build/8718654119377168929
[00:35:15.321] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:2:0) after 7m34s
[00:35:16.311] Received PubSub notification, asking Buildbucket for the build status
[00:35:16.330] Build status: SCHEDULED
[00:42:49.346] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:2:0)
[00:42:49.371] Build status: SCHEDULED
[00:42:49.371] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:4:0) after 3m52s
[00:46:41.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:4:0)
[00:46:41.368] Build status: SCHEDULED
[00:46:41.368] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:5:0) after 2m3s
[00:48:44.392] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:5:0)
[00:48:44.416] Build status: SCHEDULED
[00:48:44.416] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:6:0) after 7m15s
[00:55:59.486] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:6:0)
[00:55:59.528] Build status: SCHEDULED
[00:55:59.528] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:7:0) after 7m5s
[01:03:04.599] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:7:0)
[01:03:04.634] Build status: SCHEDULED
[01:03:04.634] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:8:0) after 7m48s
[01:10:52.711] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:8:0)
[01:10:52.734] Build status: SCHEDULED
[01:10:52.734] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:9:0) after 2m20s
[01:13:12.757] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:9:0)
[01:13:12.811] Build status: SCHEDULED
[01:13:12.811] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:10:0) after 7m49s
[01:21:01.828] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:10:0)
[01:21:01.855] Build status: SCHEDULED
[01:21:01.855] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:11:0) after 4m54s
[01:25:55.871] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:11:0)
[01:25:55.891] Build status: SCHEDULED
[01:25:55.891] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:12:0) after 1m0s
[01:26:55.911] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:12:0)
[01:26:55.935] Build status: SCHEDULED
[01:26:55.935] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:13:0) after 9m10s
[01:36:06.007] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:13:0)
[01:36:06.034] Build status: SCHEDULED
[01:36:06.034] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:14:0) after 3m57s
[01:40:03.103] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:14:0)
[01:40:03.173] Build status: SCHEDULED
[01:40:03.173] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:15:0) after 3m4s
[01:43:07.229] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:15:0)
[01:43:07.297] Build status: SCHEDULED
[01:43:07.297] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:16:0) after 3m46s
[01:46:53.347] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:16:0)
[01:46:53.374] Build status: SCHEDULED
[01:46:53.374] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:17:0) after 7m53s
[01:54:46.421] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:17:0)
[01:54:46.440] Build status: SCHEDULED
[01:54:46.440] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:18:0) after 1m0s
[01:55:46.510] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:18:0)
[01:55:46.548] Build status: SCHEDULED
[01:55:46.548] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:19:0) after 1m29s
[01:57:15.764] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:19:0)
[01:57:15.790] Build status: SCHEDULED
[01:57:15.790] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:20:0) after 9m18s
[02:06:33.810] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:20:0)
[02:06:33.831] Build status: SCHEDULED
[02:06:33.831] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:21:0) after 6m22s
[02:12:55.895] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:21:0)
[02:12:55.960] Build status: SCHEDULED
[02:12:55.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:22:0) after 5m50s
[02:18:45.985] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:22:0)
[02:18:46.002] Build status: SCHEDULED
[02:18:46.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:23:0) after 4m21s
[02:23:07.016] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:23:0)
[02:23:07.043] Build status: SCHEDULED
[02:23:07.043] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:24:0) after 3m41s
[02:26:48.067] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:24:0)
[02:26:48.083] Build status: SCHEDULED
[02:26:48.083] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:25:0) after 2m16s
[02:29:04.135] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:25:0)
[02:29:04.157] Build status: SCHEDULED
[02:29:04.157] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:26:0) after 5m5s
[02:34:09.199] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:26:0)
[02:34:09.224] Build status: SCHEDULED
[02:34:09.224] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:27:0) after 9m31s
[02:43:40.249] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:27:0)
[02:43:40.266] Build status: SCHEDULED
[02:43:40.266] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:28:0) after 9m15s
[02:52:55.334] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:28:0)
[02:52:55.353] Build status: SCHEDULED
[02:52:55.353] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:29:0) after 5m9s
[02:58:04.374] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:29:0)
[02:58:04.392] Build status: SCHEDULED
[02:58:04.392] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:30:0) after 3m18s
[03:01:22.415] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:30:0)
[03:01:22.437] Build status: SCHEDULED
[03:01:22.437] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:31:0) after 8m38s
[03:10:00.455] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:31:0)
[03:10:00.480] Build status: SCHEDULED
[03:10:00.480] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:32:0) after 3m38s
[03:13:38.551] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:32:0)
[03:13:38.573] Build status: SCHEDULED
[03:13:38.573] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:33:0) after 4m10s
[03:17:48.597] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:33:0)
[03:17:48.618] Build status: SCHEDULED
[03:17:48.618] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:34:0) after 6m51s
[03:24:39.591] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:34:0)
[03:24:39.605] Build status: SCHEDULED
[03:24:39.605] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:35:0) after 1m21s
[03:26:00.626] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:35:0)
[03:26:00.658] Build status: SCHEDULED
[03:26:00.658] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:36:0) after 2m24s
[03:28:24.794] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:36:0)
[03:28:24.815] Build status: SCHEDULED
[03:28:24.815] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:37:0) after 4m13s
[03:32:37.840] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:37:0)
[03:32:37.866] Build status: SCHEDULED
[03:32:37.866] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:38:0) after 9m1s
[03:40:58.680] Received PubSub notification, asking Buildbucket for the build status
[03:40:58.712] Build status: STARTED
[03:41:38.891] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:38:0)
[03:41:38.915] Build status: STARTED
[03:41:38.915] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8884084182222338336:40:0) after 9m56s
[03:46:31.382] Received PubSub notification, asking Buildbucket for the build status
[03:46:31.480] Build:
{
"id": "8718654119377168929",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-03T00:35:14.928997860Z",
"startTime": "2025-04-03T03:40:58.294515390Z",
"endTime": "2025-04-03T03:46:31.145087980Z",
"updateTime": "2025-04-03T03:46:31.145087980Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a1180f48449bd603ace45163febb6226d4272b5",
"ref": "refs/heads/main"
}
}
}
[03:46:31.480] Invocation finished in 3h11m18.648284396s with status SUCCEEDED