[02:04:35.345] New invocation is queued and will start shortly
[02:04:36.464] Starting the invocation (attempt 1)
[02:04:36.502] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/494b7f1305cde7c1215bbf178423c5f4153276ad"
[02:04:36.502] Popped gitiles commit info from properties and tags
[02:04:36.502] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:04:36.502] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:04:36.502] Buildbucket request:
{
"requestId": "8922763463889530704",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8922763463889530704",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d85afb6beb1915f8cf7d1e72d4e9c52dfba3b36d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d85afb6beb1915f8cf7d1e72d4e9c52dfba3b36d",
"title": "d85afb6beb1915f8cf7d1e72d4e9c52dfba3b36d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d85afb6beb1915f8cf7d1e72d4e9c52dfba3b36d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b75dbe548abb71d5f91066e63d6be9f688a4545e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b75dbe548abb71d5f91066e63d6be9f688a4545e",
"title": "b75dbe548abb71d5f91066e63d6be9f688a4545e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b75dbe548abb71d5f91066e63d6be9f688a4545e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "07f3883976500d5b873c225c8392241dd30c2101"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@07f3883976500d5b873c225c8392241dd30c2101",
"title": "07f3883976500d5b873c225c8392241dd30c2101",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/07f3883976500d5b873c225c8392241dd30c2101"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0bb3c2e2e67cefa51a11b26259adf3d9c5619eb2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0bb3c2e2e67cefa51a11b26259adf3d9c5619eb2",
"title": "0bb3c2e2e67cefa51a11b26259adf3d9c5619eb2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0bb3c2e2e67cefa51a11b26259adf3d9c5619eb2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cab494ddb20862dada542b2770bf183d3e8165fa"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cab494ddb20862dada542b2770bf183d3e8165fa",
"title": "cab494ddb20862dada542b2770bf183d3e8165fa",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cab494ddb20862dada542b2770bf183d3e8165fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "494b7f1305cde7c1215bbf178423c5f4153276ad"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@494b7f1305cde7c1215bbf178423c5f4153276ad",
"title": "494b7f1305cde7c1215bbf178423c5f4153276ad",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/494b7f1305cde7c1215bbf178423c5f4153276ad"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "494b7f1305cde7c1215bbf178423c5f4153276ad",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8922763463889530704"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:04:36.983] Scheduled build:
{
"id": "8757333401945021009",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-01T02:04:36.566019732Z",
"updateTime": "2024-02-01T02:04:36.566019732Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "494b7f1305cde7c1215bbf178423c5f4153276ad",
"ref": "refs/heads/main"
}
}
}
[02:04:36.983] Task URL: https://cr-buildbucket.appspot.com/build/8757333401945021009
[02:04:36.983] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:2:0) after 7m26s
[02:04:37.885] Received PubSub notification, asking Buildbucket for the build status
[02:04:37.981] Build status: SCHEDULED
[02:12:03.055] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:2:0)
[02:12:03.072] Build status: SCHEDULED
[02:12:03.072] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:4:0) after 2m53s
[02:14:56.087] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:4:0)
[02:14:56.105] Build status: SCHEDULED
[02:14:56.105] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:5:0) after 2m24s
[02:17:20.401] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:5:0)
[02:17:20.425] Build status: SCHEDULED
[02:17:20.425] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:6:0) after 4m19s
[02:21:39.614] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:6:0)
[02:21:39.653] Build status: SCHEDULED
[02:21:39.653] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:7:0) after 7m11s
[02:28:50.670] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:7:0)
[02:28:50.693] Build status: SCHEDULED
[02:28:50.693] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:8:0) after 4m10s
[02:33:00.759] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:8:0)
[02:33:00.792] Build status: SCHEDULED
[02:33:00.792] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:9:0) after 7m52s
[02:40:53.135] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:9:0)
[02:40:53.204] Build status: SCHEDULED
[02:40:53.204] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:10:0) after 3m27s
[02:44:20.395] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:10:0)
[02:44:20.416] Build status: SCHEDULED
[02:44:20.416] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:11:0) after 4m33s
[02:48:53.431] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:11:0)
[02:48:53.452] Build status: SCHEDULED
[02:48:53.452] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:12:0) after 3m38s
[02:52:31.468] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:12:0)
[02:52:31.485] Build status: SCHEDULED
[02:52:31.485] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:13:0) after 5m38s
[02:58:09.514] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:13:0)
[02:58:09.540] Build status: SCHEDULED
[02:58:09.540] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:14:0) after 4m3s
[03:02:12.585] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:14:0)
[03:02:12.614] Build status: SCHEDULED
[03:02:12.615] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:15:0) after 3m46s
[03:05:58.737] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:15:0)
[03:05:58.759] Build status: SCHEDULED
[03:05:58.759] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:16:0) after 6m45s
[03:12:43.875] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:16:0)
[03:12:43.927] Build status: SCHEDULED
[03:12:43.927] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:17:0) after 4m51s
[03:17:35.023] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:17:0)
[03:17:35.039] Build status: SCHEDULED
[03:17:35.039] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:18:0) after 2m45s
[03:20:20.141] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:18:0)
[03:20:20.156] Build status: SCHEDULED
[03:20:20.156] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:19:0) after 3m45s
[03:24:05.335] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:19:0)
[03:24:05.355] Build status: SCHEDULED
[03:24:05.355] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:20:0) after 6m24s
[03:30:29.565] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:20:0)
[03:30:29.607] Build status: SCHEDULED
[03:30:29.607] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:21:0) after 7m19s
[03:37:49.140] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:21:0)
[03:37:49.212] Build status: SCHEDULED
[03:37:49.212] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:22:0) after 5m31s
[03:43:20.226] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:22:0)
[03:43:20.244] Build status: SCHEDULED
[03:43:20.244] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:23:0) after 7m1s
[03:50:21.328] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:23:0)
[03:50:21.354] Build status: SCHEDULED
[03:50:21.354] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:24:0) after 5m30s
[03:55:51.415] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:24:0)
[03:55:51.438] Build status: SCHEDULED
[03:55:51.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:25:0) after 3m12s
[03:59:03.513] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:25:0)
[03:59:03.535] Build status: SCHEDULED
[03:59:03.535] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:26:0) after 8m42s
[04:07:45.683] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:26:0)
[04:07:45.722] Build status: SCHEDULED
[04:07:45.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:27:0) after 1m32s
[04:09:17.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:27:0)
[04:09:17.870] Build status: SCHEDULED
[04:09:17.870] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:28:0) after 7m7s
[04:11:32.183] Received PubSub notification, asking Buildbucket for the build status
[04:11:32.209] Build status: STARTED
[04:16:24.930] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:28:0)
[04:16:24.960] Build status: STARTED
[04:16:24.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8922763463889530704:30:0) after 1m29s
[04:17:41.741] Received PubSub notification, asking Buildbucket for the build status
[04:17:41.772] Build:
{
"id": "8757333401945021009",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-01T02:04:36.566019732Z",
"startTime": "2024-02-01T04:11:31.728794Z",
"endTime": "2024-02-01T04:17:41.160218Z",
"updateTime": "2024-02-01T04:17:41.160218Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "494b7f1305cde7c1215bbf178423c5f4153276ad",
"ref": "refs/heads/main"
}
}
}
[04:17:41.772] Invocation finished in 2h13m6.443946179s with status SUCCEEDED