[21:25:17.198] New invocation is queued and will start shortly
[21:25:18.587] Starting the invocation (attempt 1)
[21:25:18.621] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/6cac32bea3a284bd79fa7cf49ce4e39defa92fc7"
[21:25:18.621] Popped gitiles commit info from properties and tags
[21:25:18.621] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:25:18.621] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:25:18.621] Buildbucket request:
{
"requestId": "8909372685070821952",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8909372685070821952",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2ef2dcfbc773b8a9aba808930ef2158ab828d051"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2ef2dcfbc773b8a9aba808930ef2158ab828d051",
"title": "2ef2dcfbc773b8a9aba808930ef2158ab828d051",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2ef2dcfbc773b8a9aba808930ef2158ab828d051"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e948e9628ef9649856c6169b8d601fb455c1ef4b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e948e9628ef9649856c6169b8d601fb455c1ef4b",
"title": "e948e9628ef9649856c6169b8d601fb455c1ef4b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e948e9628ef9649856c6169b8d601fb455c1ef4b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f24d35840b04d28dc8f81725b6feb9febaacd5fc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f24d35840b04d28dc8f81725b6feb9febaacd5fc",
"title": "f24d35840b04d28dc8f81725b6feb9febaacd5fc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f24d35840b04d28dc8f81725b6feb9febaacd5fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6cac32bea3a284bd79fa7cf49ce4e39defa92fc7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6cac32bea3a284bd79fa7cf49ce4e39defa92fc7",
"title": "6cac32bea3a284bd79fa7cf49ce4e39defa92fc7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6cac32bea3a284bd79fa7cf49ce4e39defa92fc7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6cac32bea3a284bd79fa7cf49ce4e39defa92fc7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909372685070821952"
},
{
"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"
}
}
[21:25:19.091] Scheduled build:
{
"id": "8743942622817385409",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-27T21:25:18.697142010Z",
"updateTime": "2024-06-27T21:25:18.697142010Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6cac32bea3a284bd79fa7cf49ce4e39defa92fc7",
"ref": "refs/heads/main"
}
}
}
[21:25:19.091] Task URL: https://cr-buildbucket.appspot.com/build/8743942622817385409
[21:25:19.091] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:2:0) after 9m18s
[21:25:19.184] Received PubSub notification, asking Buildbucket for the build status
[21:25:19.201] Build status: SCHEDULED
[21:34:37.113] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:2:0)
[21:34:37.137] Build status: SCHEDULED
[21:34:37.137] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:4:0) after 1m33s
[21:36:10.176] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:4:0)
[21:36:10.207] Build status: SCHEDULED
[21:36:10.207] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:5:0) after 7m22s
[21:43:32.223] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:5:0)
[21:43:32.302] Build status: SCHEDULED
[21:43:32.302] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:6:0) after 6m31s
[21:50:03.314] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:6:0)
[21:50:03.344] Build status: SCHEDULED
[21:50:03.345] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:7:0) after 5m55s
[21:55:58.385] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:7:0)
[21:55:58.452] Build status: SCHEDULED
[21:55:58.452] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:8:0) after 4m12s
[22:00:10.496] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:8:0)
[22:00:10.516] Build status: SCHEDULED
[22:00:10.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:9:0) after 9m42s
[22:09:52.533] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:9:0)
[22:09:52.557] Build status: SCHEDULED
[22:09:52.557] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:10:0) after 2m7s
[22:11:59.582] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:10:0)
[22:11:59.598] Build status: SCHEDULED
[22:11:59.598] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:11:0) after 4m50s
[22:16:49.604] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:11:0)
[22:16:49.632] Build status: SCHEDULED
[22:16:49.633] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:12:0) after 3m33s
[22:20:22.719] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:12:0)
[22:20:22.775] Build status: SCHEDULED
[22:20:22.775] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:13:0) after 2m46s
[22:23:08.791] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:13:0)
[22:23:08.810] Build status: SCHEDULED
[22:23:08.810] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:14:0) after 5m24s
[22:28:32.883] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:14:0)
[22:28:32.901] Build status: SCHEDULED
[22:28:32.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:15:0) after 6m58s
[22:35:30.921] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:15:0)
[22:35:30.945] Build status: SCHEDULED
[22:35:30.945] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:16:0) after 8m30s
[22:44:00.962] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:16:0)
[22:44:00.994] Build status: SCHEDULED
[22:44:00.994] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:17:0) after 4m37s
[22:48:38.012] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:17:0)
[22:48:38.032] Build status: SCHEDULED
[22:48:38.032] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:18:0) after 6m24s
[22:55:02.100] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:18:0)
[22:55:02.124] Build status: SCHEDULED
[22:55:02.124] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:19:0) after 4m4s
[22:59:06.142] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:19:0)
[22:59:06.222] Build status: SCHEDULED
[22:59:06.222] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:20:0) after 4m35s
[23:03:41.246] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:20:0)
[23:03:41.292] Build status: SCHEDULED
[23:03:41.292] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:21:0) after 6m36s
[23:10:17.357] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:21:0)
[23:10:17.387] Build status: SCHEDULED
[23:10:17.387] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:22:0) after 7m12s
[23:17:29.408] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:22:0)
[23:17:29.456] Build status: SCHEDULED
[23:17:29.456] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:23:0) after 8m40s
[23:26:09.489] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:23:0)
[23:26:09.538] Build status: SCHEDULED
[23:26:09.538] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:24:0) after 8m9s
[23:34:18.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:24:0)
[23:34:18.577] Build status: SCHEDULED
[23:34:18.577] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:25:0) after 5m21s
[23:39:40.030] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:25:0)
[23:39:40.065] Build status: SCHEDULED
[23:39:40.065] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:26:0) after 7m8s
[23:46:48.082] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:26:0)
[23:46:48.103] Build status: SCHEDULED
[23:46:48.103] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:27:0) after 2m29s
[23:49:17.433] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:27:0)
[23:49:17.453] Build status: SCHEDULED
[23:49:17.453] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:28:0) after 6m43s
[23:53:29.368] Received PubSub notification, asking Buildbucket for the build status
[23:53:29.390] Build status: STARTED
[23:56:00.451] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:28:0)
[23:56:00.475] Build status: STARTED
[23:56:00.475] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8909372685070821952:30:0) after 7m58s
[23:57:58.704] Received PubSub notification, asking Buildbucket for the build status
[23:57:58.781] Build:
{
"id": "8743942622817385409",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-27T21:25:18.697142010Z",
"startTime": "2024-06-27T23:53:29.063091487Z",
"endTime": "2024-06-27T23:57:58.393167024Z",
"updateTime": "2024-06-27T23:57:58.393167024Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6cac32bea3a284bd79fa7cf49ce4e39defa92fc7",
"ref": "refs/heads/main"
}
}
}
[23:57:58.781] Invocation finished in 2h32m41.617075926s with status SUCCEEDED