[01:14:43.108] New invocation is queued and will start shortly
[01:14:44.285] Starting the invocation (attempt 1)
[01:14:44.311] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/a3a5a3421efd15510c5525c7fbcc2ae6f70f9392"
[01:14:44.311] Popped gitiles commit info from properties and tags
[01:14:44.311] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:14:44.311] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:14:44.311] Buildbucket request:
{
"requestId": "8903741238528657168",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8903741238528657168",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a99f4b80ec6778d314d8a4e5b1654a8e63e145fc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a99f4b80ec6778d314d8a4e5b1654a8e63e145fc",
"title": "a99f4b80ec6778d314d8a4e5b1654a8e63e145fc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a99f4b80ec6778d314d8a4e5b1654a8e63e145fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d0a90cd215d657fb2ee3240310fdcb3b0f1c1819"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d0a90cd215d657fb2ee3240310fdcb3b0f1c1819",
"title": "d0a90cd215d657fb2ee3240310fdcb3b0f1c1819",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d0a90cd215d657fb2ee3240310fdcb3b0f1c1819"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6338bfb4bdf1a3b6827f31e300d4eeacbfc92e0e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6338bfb4bdf1a3b6827f31e300d4eeacbfc92e0e",
"title": "6338bfb4bdf1a3b6827f31e300d4eeacbfc92e0e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6338bfb4bdf1a3b6827f31e300d4eeacbfc92e0e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "95d9586026ee8f67d69042f58bf23a1d23f46523"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@95d9586026ee8f67d69042f58bf23a1d23f46523",
"title": "95d9586026ee8f67d69042f58bf23a1d23f46523",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/95d9586026ee8f67d69042f58bf23a1d23f46523"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "56257a2e0ec93ac967898094161d9816b5f50f1a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@56257a2e0ec93ac967898094161d9816b5f50f1a",
"title": "56257a2e0ec93ac967898094161d9816b5f50f1a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/56257a2e0ec93ac967898094161d9816b5f50f1a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "102a0f6737990f7ebed2c4b53124a5724c1d3242"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@102a0f6737990f7ebed2c4b53124a5724c1d3242",
"title": "102a0f6737990f7ebed2c4b53124a5724c1d3242",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/102a0f6737990f7ebed2c4b53124a5724c1d3242"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a3a5a3421efd15510c5525c7fbcc2ae6f70f9392"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a3a5a3421efd15510c5525c7fbcc2ae6f70f9392",
"title": "a3a5a3421efd15510c5525c7fbcc2ae6f70f9392",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a3a5a3421efd15510c5525c7fbcc2ae6f70f9392"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "a3a5a3421efd15510c5525c7fbcc2ae6f70f9392",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8903741238528657168"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:14:44.722] Scheduled build:
{
"id": "8738311176544444913",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2024-08-29T01:14:44.371530843Z",
"updateTime": "2024-08-29T01:14:44.371530843Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "a3a5a3421efd15510c5525c7fbcc2ae6f70f9392",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:14:44.722] Task URL: https://cr-buildbucket.appspot.com/build/8738311176544444913
[01:14:44.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:2:0) after 5m23s
[01:14:45.755] Received PubSub notification, asking Buildbucket for the build status
[01:14:45.782] Build status: SCHEDULED
[01:20:07.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:2:0)
[01:20:07.778] Build status: SCHEDULED
[01:20:07.778] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:4:0) after 3m17s
[01:23:24.849] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:4:0)
[01:23:24.871] Build status: SCHEDULED
[01:23:24.871] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:5:0) after 1m11s
[01:24:35.907] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:5:0)
[01:24:35.933] Build status: SCHEDULED
[01:24:35.933] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:6:0) after 3m18s
[01:27:53.947] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:6:0)
[01:27:53.977] Build status: SCHEDULED
[01:27:53.977] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:7:0) after 5m37s
[01:33:31.042] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:7:0)
[01:33:31.067] Build status: SCHEDULED
[01:33:31.067] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:8:0) after 2m48s
[01:36:19.109] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:8:0)
[01:36:19.138] Build status: SCHEDULED
[01:36:19.138] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:9:0) after 6m11s
[01:42:30.157] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:9:0)
[01:42:30.180] Build status: SCHEDULED
[01:42:30.180] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:10:0) after 2m2s
[01:44:32.195] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:10:0)
[01:44:32.222] Build status: SCHEDULED
[01:44:32.222] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:11:0) after 3m0s
[01:47:32.205] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:11:0)
[01:47:32.236] Build status: SCHEDULED
[01:47:32.236] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:12:0) after 9m10s
[01:56:42.261] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:12:0)
[01:56:42.281] Build status: SCHEDULED
[01:56:42.281] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:13:0) after 3m0s
[01:59:42.301] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:13:0)
[01:59:42.355] Build status: SCHEDULED
[01:59:42.355] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:14:0) after 7m53s
[02:07:35.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:14:0)
[02:07:35.463] Build status: SCHEDULED
[02:07:35.463] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:15:0) after 4m53s
[02:12:28.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:15:0)
[02:12:28.575] Build status: SCHEDULED
[02:12:28.575] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:16:0) after 9m52s
[02:22:20.577] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:16:0)
[02:22:20.794] Build status: SCHEDULED
[02:22:20.794] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:17:0) after 8m23s
[02:30:43.816] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:17:0)
[02:30:43.842] Build status: SCHEDULED
[02:30:43.842] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:18:0) after 5m56s
[02:36:40.045] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:18:0)
[02:36:40.069] Build status: SCHEDULED
[02:36:40.069] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:19:0) after 2m3s
[02:38:43.801] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:19:0)
[02:38:43.822] Build status: SCHEDULED
[02:38:43.822] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:20:0) after 1m1s
[02:39:44.867] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:20:0)
[02:39:44.886] Build status: SCHEDULED
[02:39:44.886] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:21:0) after 5m35s
[02:45:19.904] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:21:0)
[02:45:19.955] Build status: SCHEDULED
[02:45:19.955] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:22:0) after 1m56s
[02:47:16.073] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:22:0)
[02:47:16.091] Build status: SCHEDULED
[02:47:16.091] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:23:0) after 4m1s
[02:51:17.105] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:23:0)
[02:51:17.123] Build status: SCHEDULED
[02:51:17.123] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:24:0) after 8m13s
[02:59:30.161] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:24:0)
[02:59:30.186] Build status: SCHEDULED
[02:59:30.186] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:25:0) after 4m18s
[03:03:48.308] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:25:0)
[03:03:48.354] Build status: SCHEDULED
[03:03:48.354] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:26:0) after 8m22s
[03:12:10.341] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:26:0)
[03:12:10.368] Build status: SCHEDULED
[03:12:10.369] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:27:0) after 5m42s
[03:17:52.391] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:27:0)
[03:17:52.414] Build status: SCHEDULED
[03:17:52.414] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:28:0) after 7m53s
[03:25:45.453] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:28:0)
[03:25:45.475] Build status: SCHEDULED
[03:25:45.475] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:29:0) after 5m46s
[03:31:31.502] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:29:0)
[03:31:31.520] Build status: SCHEDULED
[03:31:31.520] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:30:0) after 8m58s
[03:40:29.834] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:30:0)
[03:40:29.852] Build status: SCHEDULED
[03:40:29.852] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:31:0) after 7m22s
[03:47:51.905] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:31:0)
[03:47:51.921] Build status: SCHEDULED
[03:47:51.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:32:0) after 9m58s
[03:55:09.951] Received PubSub notification, asking Buildbucket for the build status
[03:55:09.969] Build status: STARTED
[03:57:50.031] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:32:0)
[03:57:50.058] Build status: STARTED
[03:57:50.058] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:34:0) after 2m12s
[04:00:02.398] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:34:0)
[04:00:02.428] Build status: STARTED
[04:00:02.428] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8903741238528657168:35:0) after 8m49s
[04:00:11.877] Received PubSub notification, asking Buildbucket for the build status
[04:00:11.895] Build:
{
"id": "8738311176544444913",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2024-08-29T01:14:44.371530843Z",
"startTime": "2024-08-29T03:55:09.778833806Z",
"endTime": "2024-08-29T04:00:10.331270698Z",
"updateTime": "2024-08-29T04:00:10.331270698Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "a3a5a3421efd15510c5525c7fbcc2ae6f70f9392",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:00:11.895] Invocation finished in 2h45m28.799418681s with status SUCCEEDED