[21:33:42.262] New invocation is queued and will start shortly
[21:33:43.454] Starting the invocation (attempt 1)
[21:33:43.477] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/37845ac8c632b2616c7a4ef96ae0a0a4ee41b443"
[21:33:43.477] Popped gitiles commit info from properties and tags
[21:33:43.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:33:43.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:33:43.479] Buildbucket request:
{
"requestId": "8893427089359169264",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893427089359169264",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5356fe88f9d39b7b99fde5f73a346e3ae6f51f74"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5356fe88f9d39b7b99fde5f73a346e3ae6f51f74",
"title": "5356fe88f9d39b7b99fde5f73a346e3ae6f51f74",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5356fe88f9d39b7b99fde5f73a346e3ae6f51f74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d78f076c7bcd43ab36925ae65615b904ede43c21"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d78f076c7bcd43ab36925ae65615b904ede43c21",
"title": "d78f076c7bcd43ab36925ae65615b904ede43c21",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d78f076c7bcd43ab36925ae65615b904ede43c21"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "330670beca70b4de999ca96328eb8e8451427848"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@330670beca70b4de999ca96328eb8e8451427848",
"title": "330670beca70b4de999ca96328eb8e8451427848",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/330670beca70b4de999ca96328eb8e8451427848"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "49ca81080f29a01d3cd7d23ec596f46fd0591292"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@49ca81080f29a01d3cd7d23ec596f46fd0591292",
"title": "49ca81080f29a01d3cd7d23ec596f46fd0591292",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/49ca81080f29a01d3cd7d23ec596f46fd0591292"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "983119ef6217fbd8cc1e3f2914c41b57a246dd21"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@983119ef6217fbd8cc1e3f2914c41b57a246dd21",
"title": "983119ef6217fbd8cc1e3f2914c41b57a246dd21",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/983119ef6217fbd8cc1e3f2914c41b57a246dd21"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "982b2250bda11cc66e25fe5f41151cc9f9e3f23f"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@982b2250bda11cc66e25fe5f41151cc9f9e3f23f",
"title": "982b2250bda11cc66e25fe5f41151cc9f9e3f23f",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/982b2250bda11cc66e25fe5f41151cc9f9e3f23f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d69cd4a6a1f47d878582a07b1c7a4a4f44a17988"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d69cd4a6a1f47d878582a07b1c7a4a4f44a17988",
"title": "d69cd4a6a1f47d878582a07b1c7a4a4f44a17988",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d69cd4a6a1f47d878582a07b1c7a4a4f44a17988"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "37845ac8c632b2616c7a4ef96ae0a0a4ee41b443"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@37845ac8c632b2616c7a4ef96ae0a0a4ee41b443",
"title": "37845ac8c632b2616c7a4ef96ae0a0a4ee41b443",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/37845ac8c632b2616c7a4ef96ae0a0a4ee41b443"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "37845ac8c632b2616c7a4ef96ae0a0a4ee41b443",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893427089359169264"
},
{
"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"
}
}
[21:33:43.896] Scheduled build:
{
"id": "8727997027362574129",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-20T21:33:43.542689466Z",
"updateTime": "2024-12-20T21:33:43.542689466Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "37845ac8c632b2616c7a4ef96ae0a0a4ee41b443",
"ref": "refs/heads/main"
}
}
}
[21:33:43.896] Task URL: https://cr-buildbucket.appspot.com/build/8727997027362574129
[21:33:43.896] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:2:0) after 6m29s
[21:33:44.858] Received PubSub notification, asking Buildbucket for the build status
[21:33:44.877] Build status: SCHEDULED
[21:40:12.914] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:2:0)
[21:40:12.939] Build status: SCHEDULED
[21:40:12.939] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:4:0) after 3m2s
[21:43:14.960] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:4:0)
[21:43:14.989] Build status: SCHEDULED
[21:43:14.989] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:5:0) after 3m47s
[21:47:02.093] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:5:0)
[21:47:02.126] Build status: SCHEDULED
[21:47:02.126] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:6:0) after 6m48s
[21:53:50.198] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:6:0)
[21:53:50.222] Build status: SCHEDULED
[21:53:50.222] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:7:0) after 9m36s
[22:03:26.355] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:7:0)
[22:03:26.380] Build status: SCHEDULED
[22:03:26.380] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:8:0) after 9m31s
[22:12:57.501] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:8:0)
[22:12:57.528] Build status: SCHEDULED
[22:12:57.528] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:9:0) after 7m52s
[22:20:49.718] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:9:0)
[22:20:49.743] Build status: SCHEDULED
[22:20:49.743] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:10:0) after 6m38s
[22:27:27.760] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:10:0)
[22:27:27.779] Build status: SCHEDULED
[22:27:27.779] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:11:0) after 7m29s
[22:34:56.797] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:11:0)
[22:34:56.822] Build status: SCHEDULED
[22:34:56.822] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:12:0) after 8m41s
[22:43:37.847] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:12:0)
[22:43:37.861] Build status: SCHEDULED
[22:43:37.861] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:13:0) after 3m24s
[22:47:01.932] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:13:0)
[22:47:01.956] Build status: SCHEDULED
[22:47:01.956] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:14:0) after 5m55s
[22:52:57.017] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:14:0)
[22:52:57.049] Build status: SCHEDULED
[22:52:57.049] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:15:0) after 9m3s
[23:02:00.393] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:15:0)
[23:02:00.417] Build status: SCHEDULED
[23:02:00.417] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:16:0) after 4m42s
[23:06:42.445] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:16:0)
[23:06:42.470] Build status: SCHEDULED
[23:06:42.470] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:17:0) after 6m48s
[23:13:30.730] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:17:0)
[23:13:30.758] Build status: SCHEDULED
[23:13:30.758] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:18:0) after 3m59s
[23:17:29.737] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:18:0)
[23:17:29.756] Build status: SCHEDULED
[23:17:29.756] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:19:0) after 3m7s
[23:20:36.978] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:19:0)
[23:20:36.999] Build status: SCHEDULED
[23:20:36.999] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:20:0) after 5m23s
[23:26:00.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:20:0)
[23:26:00.053] Build status: SCHEDULED
[23:26:00.053] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:21:0) after 8m15s
[23:34:15.080] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:21:0)
[23:34:15.098] Build status: SCHEDULED
[23:34:15.098] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:22:0) after 6m43s
[23:40:58.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:22:0)
[23:40:58.164] Build status: SCHEDULED
[23:40:58.164] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:23:0) after 5m36s
[23:46:34.241] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:23:0)
[23:46:34.299] Build status: SCHEDULED
[23:46:34.299] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:24:0) after 8m46s
[23:55:20.325] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:24:0)
[23:55:20.348] Build status: SCHEDULED
[23:55:20.348] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:25:0) after 3m3s
[23:58:23.368] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:25:0)
[23:58:23.405] Build status: SCHEDULED
[23:58:23.405] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:26:0) after 8m57s
[00:07:20.438] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:26:0)
[00:07:20.464] Build status: SCHEDULED
[00:07:20.464] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:27:0) after 5m6s
[00:12:26.491] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:27:0)
[00:12:26.556] Build status: SCHEDULED
[00:12:26.556] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:28:0) after 1m22s
[00:13:48.637] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:28:0)
[00:13:48.844] Build status: SCHEDULED
[00:13:48.844] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-arm-zephyr:8893427089359169264:29:0) after 9m21s
[00:16:40.760] Received PubSub notification, asking Buildbucket for the build status
[00:16:40.784] Build status: STARTED
[00:21:43.827] Received PubSub notification, asking Buildbucket for the build status
[00:21:43.844] Build:
{
"id": "8727997027362574129",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-arm-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-20T21:33:43.542689466Z",
"startTime": "2024-12-21T00:16:40.527735909Z",
"endTime": "2024-12-21T00:21:43.324470363Z",
"updateTime": "2024-12-21T00:21:43.324470363Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "37845ac8c632b2616c7a4ef96ae0a0a4ee41b443",
"ref": "refs/heads/main"
}
}
}
[00:21:43.844] Invocation finished in 2h48m1.590603155s with status SUCCEEDED