[00:18:20.270] New invocation is queued and will start shortly
[00:18:21.582] Starting the invocation (attempt 1)
[00:18:21.615] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/99196c2972ba71677be6a84a33698902076e1837"
[00:18:21.615] Popped gitiles commit info from properties and tags
[00:18:21.615] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:18:21.615] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:18:21.616] Buildbucket request:
{
"requestId": "8931195666510271536",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931195666510271536",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a350fb5a873085d35a670037c1db6f2407d87633"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a350fb5a873085d35a670037c1db6f2407d87633",
"title": "a350fb5a873085d35a670037c1db6f2407d87633",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a350fb5a873085d35a670037c1db6f2407d87633"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "55c0af095329ff5f609e4d5e6123e9a79f7fe2d0"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@55c0af095329ff5f609e4d5e6123e9a79f7fe2d0",
"title": "55c0af095329ff5f609e4d5e6123e9a79f7fe2d0",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/55c0af095329ff5f609e4d5e6123e9a79f7fe2d0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f48b7c0db427b8f7ecd5901cf20e3a9a51744804"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f48b7c0db427b8f7ecd5901cf20e3a9a51744804",
"title": "f48b7c0db427b8f7ecd5901cf20e3a9a51744804",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f48b7c0db427b8f7ecd5901cf20e3a9a51744804"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "99196c2972ba71677be6a84a33698902076e1837"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@99196c2972ba71677be6a84a33698902076e1837",
"title": "99196c2972ba71677be6a84a33698902076e1837",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/99196c2972ba71677be6a84a33698902076e1837"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "99196c2972ba71677be6a84a33698902076e1837",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931195666510271536"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-zephyr"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:18:22.160] Scheduled build:
{
"id": "8765765604368298577",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2023-10-31T00:18:21.684856828Z",
"updateTime": "2023-10-31T00:18:21.684856828Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "99196c2972ba71677be6a84a33698902076e1837",
"ref": "refs/heads/main"
}
}
}
[00:18:22.160] Task URL: https://cr-buildbucket.appspot.com/build/8765765604368298577
[00:18:22.160] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:2:0) after 1m50s
[00:18:22.979] Received PubSub notification, asking Buildbucket for the build status
[00:18:23.000] Build status: SCHEDULED
[00:20:12.284] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:2:0)
[00:20:12.382] Build status: SCHEDULED
[00:20:12.382] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:4:0) after 3m16s
[00:23:28.367] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:4:0)
[00:23:28.384] Build status: SCHEDULED
[00:23:28.384] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:5:0) after 6m9s
[00:29:37.402] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:5:0)
[00:29:37.540] Build status: SCHEDULED
[00:29:37.540] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:6:0) after 3m12s
[00:32:49.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:6:0)
[00:32:49.584] Build status: SCHEDULED
[00:32:49.584] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:7:0) after 2m40s
[00:35:29.640] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:7:0)
[00:35:29.665] Build status: SCHEDULED
[00:35:29.665] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:8:0) after 6m30s
[00:41:59.689] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:8:0)
[00:41:59.707] Build status: SCHEDULED
[00:41:59.707] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:9:0) after 5m21s
[00:47:20.777] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:9:0)
[00:47:20.797] Build status: SCHEDULED
[00:47:20.797] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:10:0) after 6m45s
[00:54:05.852] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:10:0)
[00:54:05.930] Build status: SCHEDULED
[00:54:05.930] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:11:0) after 7m28s
[01:01:34.006] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:11:0)
[01:01:34.046] Build status: SCHEDULED
[01:01:34.046] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:12:0) after 4m56s
[01:06:30.126] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:12:0)
[01:06:30.159] Build status: SCHEDULED
[01:06:30.159] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:13:0) after 4m55s
[01:11:25.225] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:13:0)
[01:11:25.262] Build status: SCHEDULED
[01:11:25.262] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:14:0) after 8m1s
[01:19:26.278] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:14:0)
[01:19:26.302] Build status: SCHEDULED
[01:19:26.302] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:15:0) after 2m24s
[01:21:50.320] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:15:0)
[01:21:50.340] Build status: SCHEDULED
[01:21:50.340] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:16:0) after 3m23s
[01:25:13.352] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:16:0)
[01:25:13.396] Build status: SCHEDULED
[01:25:13.396] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:17:0) after 9m8s
[01:34:22.205] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:17:0)
[01:34:22.438] Build status: SCHEDULED
[01:34:22.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:18:0) after 1m51s
[01:36:13.555] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:18:0)
[01:36:13.575] Build status: SCHEDULED
[01:36:13.575] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:19:0) after 1m14s
[01:37:27.612] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:19:0)
[01:37:27.635] Build status: SCHEDULED
[01:37:27.635] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:20:0) after 7m0s
[01:44:27.886] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:20:0)
[01:44:27.912] Build status: SCHEDULED
[01:44:27.912] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:21:0) after 9m12s
[01:53:40.077] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:21:0)
[01:53:40.268] Build status: SCHEDULED
[01:53:40.268] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:22:0) after 2m17s
[01:55:57.605] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:22:0)
[01:55:57.624] Build status: SCHEDULED
[01:55:57.624] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:23:0) after 6m22s
[02:02:19.737] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:23:0)
[02:02:19.767] Build status: SCHEDULED
[02:02:19.769] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:24:0) after 4m38s
[02:06:57.791] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:24:0)
[02:06:57.811] Build status: SCHEDULED
[02:06:57.811] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:25:0) after 2m58s
[02:09:55.835] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:25:0)
[02:09:55.860] Build status: SCHEDULED
[02:09:55.860] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:26:0) after 4m2s
[02:13:57.996] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:26:0)
[02:13:58.029] Build status: SCHEDULED
[02:13:58.029] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:27:0) after 9m46s
[02:23:44.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:27:0)
[02:23:44.173] Build status: SCHEDULED
[02:23:44.173] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:28:0) after 9m37s
[02:33:21.651] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:28:0)
[02:33:21.678] Build status: SCHEDULED
[02:33:21.678] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:29:0) after 7m18s
[02:40:39.707] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:29:0)
[02:40:39.730] Build status: SCHEDULED
[02:40:39.730] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:30:0) after 3m31s
[02:44:10.898] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:30:0)
[02:44:10.932] Build status: SCHEDULED
[02:44:10.932] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:31:0) after 7m14s
[02:51:24.949] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:31:0)
[02:51:24.968] Build status: SCHEDULED
[02:51:24.968] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:32:0) after 5m40s
[02:57:05.088] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:32:0)
[02:57:05.173] Build status: SCHEDULED
[02:57:05.173] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8931195666510271536:33:0) after 6m47s
[02:58:19.574] Received PubSub notification, asking Buildbucket for the build status
[02:58:19.598] Build status: STARTED
[03:02:14.757] Received PubSub notification, asking Buildbucket for the build status
[03:02:14.798] Build:
{
"id": "8765765604368298577",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2023-10-31T00:18:21.684856828Z",
"startTime": "2023-10-31T02:58:19.029347Z",
"endTime": "2023-10-31T03:02:13.764470Z",
"updateTime": "2023-10-31T03:02:13.764470Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "99196c2972ba71677be6a84a33698902076e1837",
"ref": "refs/heads/main"
}
}
}
[03:02:14.798] Invocation finished in 2h43m54.54050735s with status FAILED