[23:16:54.867] New invocation is queued and will start shortly
[23:16:55.956] Starting the invocation (attempt 1)
[23:16:55.987] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4f238babeb357b7739992449f1212a94ee1afa89"
[23:16:55.987] Popped gitiles commit info from properties and tags
[23:16:55.987] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:55.987] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:55.988] Buildbucket request:
{
"requestId": "8914076704287481008",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914076704287481008",
"job": "pigweed/pigweed.dev.ci-envtest-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8741fcf0a55d5bba5cde1b80fa7a10e4bd43049c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8741fcf0a55d5bba5cde1b80fa7a10e4bd43049c",
"title": "8741fcf0a55d5bba5cde1b80fa7a10e4bd43049c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8741fcf0a55d5bba5cde1b80fa7a10e4bd43049c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8dfb6ef0937612fa318a8312c7ac3ad02b1a3325"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8dfb6ef0937612fa318a8312c7ac3ad02b1a3325",
"title": "8dfb6ef0937612fa318a8312c7ac3ad02b1a3325",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8dfb6ef0937612fa318a8312c7ac3ad02b1a3325"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "15660a9a2d0102e297854b262049e29b8a7378e8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@15660a9a2d0102e297854b262049e29b8a7378e8",
"title": "15660a9a2d0102e297854b262049e29b8a7378e8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/15660a9a2d0102e297854b262049e29b8a7378e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d60a7315d676b9d69dc37b0ebfa8a27afe7bbeed"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d60a7315d676b9d69dc37b0ebfa8a27afe7bbeed",
"title": "d60a7315d676b9d69dc37b0ebfa8a27afe7bbeed",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d60a7315d676b9d69dc37b0ebfa8a27afe7bbeed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4f238babeb357b7739992449f1212a94ee1afa89"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4f238babeb357b7739992449f1212a94ee1afa89",
"title": "4f238babeb357b7739992449f1212a94ee1afa89",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4f238babeb357b7739992449f1212a94ee1afa89"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4f238babeb357b7739992449f1212a94ee1afa89",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914076704287481008"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-envtest-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:16:56.508] Scheduled build:
{
"id": "8748646642362522961",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2024-05-06T23:16:56.073338614Z",
"updateTime": "2024-05-06T23:16:56.073338614Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4f238babeb357b7739992449f1212a94ee1afa89",
"ref": "refs/heads/main"
}
}
}
[23:16:56.508] Task URL: https://cr-buildbucket.appspot.com/build/8748646642362522961
[23:16:56.508] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:2:0) after 2m43s
[23:16:56.701] Received PubSub notification, asking Buildbucket for the build status
[23:16:56.778] Build status: SCHEDULED
[23:19:39.524] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:2:0)
[23:19:39.559] Build status: SCHEDULED
[23:19:39.559] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:4:0) after 6m58s
[23:26:38.032] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:4:0)
[23:26:38.068] Build status: SCHEDULED
[23:26:38.068] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:5:0) after 1m28s
[23:28:06.362] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:5:0)
[23:28:06.400] Build status: SCHEDULED
[23:28:06.400] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:6:0) after 1m59s
[23:30:05.578] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:6:0)
[23:30:05.606] Build status: SCHEDULED
[23:30:05.606] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:7:0) after 4m54s
[23:34:59.722] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:7:0)
[23:34:59.806] Build status: SCHEDULED
[23:34:59.806] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:8:0) after 1m48s
[23:36:47.824] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:8:0)
[23:36:47.847] Build status: SCHEDULED
[23:36:47.847] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:9:0) after 7m1s
[23:43:48.837] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:9:0)
[23:43:48.862] Build status: SCHEDULED
[23:43:48.862] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:10:0) after 3m24s
[23:47:12.878] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:10:0)
[23:47:12.904] Build status: SCHEDULED
[23:47:12.904] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:11:0) after 4m44s
[23:51:56.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:11:0)
[23:51:56.977] Build status: SCHEDULED
[23:51:56.977] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:12:0) after 5m31s
[23:57:27.994] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:12:0)
[23:57:28.013] Build status: SCHEDULED
[23:57:28.013] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:13:0) after 2m52s
[00:00:20.066] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:13:0)
[00:00:20.262] Build status: SCHEDULED
[00:00:20.262] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:14:0) after 1m20s
[00:01:40.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:14:0)
[00:01:40.387] Build status: SCHEDULED
[00:01:40.387] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:15:0) after 7m37s
[00:09:17.473] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:15:0)
[00:09:17.530] Build status: SCHEDULED
[00:09:17.530] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:16:0) after 2m31s
[00:11:48.516] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:16:0)
[00:11:48.548] Build status: SCHEDULED
[00:11:48.548] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:17:0) after 8m44s
[00:20:32.567] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:17:0)
[00:20:32.698] Build status: SCHEDULED
[00:20:32.698] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:18:0) after 7m28s
[00:28:00.767] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:18:0)
[00:28:00.819] Build status: SCHEDULED
[00:28:00.819] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:19:0) after 9m36s
[00:37:36.890] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:19:0)
[00:37:36.912] Build status: SCHEDULED
[00:37:36.912] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:20:0) after 4m33s
[00:42:10.024] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:20:0)
[00:42:10.047] Build status: SCHEDULED
[00:42:10.047] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:21:0) after 5m34s
[00:47:44.136] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:21:0)
[00:47:44.156] Build status: SCHEDULED
[00:47:44.156] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:22:0) after 3m26s
[00:51:10.231] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:22:0)
[00:51:10.261] Build status: SCHEDULED
[00:51:10.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:23:0) after 2m2s
[00:53:12.282] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:23:0)
[00:53:12.309] Build status: SCHEDULED
[00:53:12.309] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:24:0) after 2m38s
[00:55:50.328] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:24:0)
[00:55:50.367] Build status: SCHEDULED
[00:55:50.367] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:25:0) after 2m44s
[00:58:34.388] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:25:0)
[00:58:34.426] Build status: SCHEDULED
[00:58:34.426] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:26:0) after 3m31s
[01:02:05.472] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:26:0)
[01:02:05.493] Build status: SCHEDULED
[01:02:05.493] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:27:0) after 3m57s
[01:06:02.708] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:27:0)
[01:06:02.743] Build status: SCHEDULED
[01:06:02.743] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:28:0) after 4m49s
[01:10:51.766] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:28:0)
[01:10:51.824] Build status: SCHEDULED
[01:10:51.824] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:29:0) after 4m37s
[01:15:28.838] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:29:0)
[01:15:28.867] Build status: SCHEDULED
[01:15:28.868] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:30:0) after 5m32s
[01:21:00.850] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:30:0)
[01:21:00.889] Build status: SCHEDULED
[01:21:00.889] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:31:0) after 2m30s
[01:23:30.910] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:31:0)
[01:23:30.928] Build status: SCHEDULED
[01:23:30.928] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:32:0) after 3m47s
[01:27:17.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:32:0)
[01:27:17.972] Build status: SCHEDULED
[01:27:17.972] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:33:0) after 8m24s
[01:35:42.031] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:33:0)
[01:35:42.077] Build status: SCHEDULED
[01:35:42.077] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:34:0) after 3m19s
[01:36:09.592] Received PubSub notification, asking Buildbucket for the build status
[01:36:09.623] Build status: STARTED
[01:39:01.132] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:34:0)
[01:39:01.170] Build status: STARTED
[01:39:01.170] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8914076704287481008:36:0) after 2m58s
[01:41:27.069] Received PubSub notification, asking Buildbucket for the build status
[01:41:27.089] Build:
{
"id": "8748646642362522961",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2024-05-06T23:16:56.073338614Z",
"startTime": "2024-05-07T01:36:09.311416806Z",
"endTime": "2024-05-07T01:41:26.037177432Z",
"updateTime": "2024-05-07T01:41:26.037177432Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4f238babeb357b7739992449f1212a94ee1afa89",
"ref": "refs/heads/main"
}
}
}
[01:41:27.089] Invocation finished in 2h24m32.235309202s with status SUCCEEDED