[02:10:03.037] New invocation is queued and will start shortly
[02:10:04.367] Starting the invocation (attempt 1)
[02:10:04.402] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/experimental/+/03ec00caaffca5317a0df828b531dc0e2df8c0f4"
[02:10:04.402] Popped gitiles commit info from properties and tags
[02:10:04.402] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:10:04.402] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:10:04.402] Buildbucket request:
{
"requestId": "8928470729156160400",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928470729156160400",
"job": "pigweed/experimental.dev.ci-experimental-full",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "e4ca00039fd31343cec970917b566b17916f6519"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@e4ca00039fd31343cec970917b566b17916f6519",
"title": "e4ca00039fd31343cec970917b566b17916f6519",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/e4ca00039fd31343cec970917b566b17916f6519"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "d3a1e65f2ebb8ae9ecd4982e8693ab04c2a7c230"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@d3a1e65f2ebb8ae9ecd4982e8693ab04c2a7c230",
"title": "d3a1e65f2ebb8ae9ecd4982e8693ab04c2a7c230",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/d3a1e65f2ebb8ae9ecd4982e8693ab04c2a7c230"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "fe23dd372565c39296faf4dd882bf0b88c4f2975"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@fe23dd372565c39296faf4dd882bf0b88c4f2975",
"title": "fe23dd372565c39296faf4dd882bf0b88c4f2975",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/fe23dd372565c39296faf4dd882bf0b88c4f2975"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "4377de309d676658ef676d422b083bb355b7ff06"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@4377de309d676658ef676d422b083bb355b7ff06",
"title": "4377de309d676658ef676d422b083bb355b7ff06",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/4377de309d676658ef676d422b083bb355b7ff06"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "03ec00caaffca5317a0df828b531dc0e2df8c0f4"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@03ec00caaffca5317a0df828b531dc0e2df8c0f4",
"title": "03ec00caaffca5317a0df828b531dc0e2df8c0f4",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/03ec00caaffca5317a0df828b531dc0e2df8c0f4"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "03ec00caaffca5317a0df828b531dc0e2df8c0f4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928470729156160400"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.ci-experimental-full"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:10:04.938] Scheduled build:
{
"id": "8763040666955196337",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"createdBy": "project:pigweed",
"createTime": "2023-11-30T02:10:04.509266870Z",
"updateTime": "2023-11-30T02:10:04.509266870Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "03ec00caaffca5317a0df828b531dc0e2df8c0f4",
"ref": "refs/heads/main"
}
}
}
[02:10:04.938] Task URL: https://cr-buildbucket.appspot.com/build/8763040666955196337
[02:10:04.938] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:2:0) after 9m48s
[02:10:05.849] Received PubSub notification, asking Buildbucket for the build status
[02:10:05.898] Build status: SCHEDULED
[02:19:52.952] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:2:0)
[02:19:53.044] Build status: SCHEDULED
[02:19:53.045] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:4:0) after 8m42s
[02:28:35.262] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:4:0)
[02:28:35.291] Build status: SCHEDULED
[02:28:35.291] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:5:0) after 7m0s
[02:35:35.520] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:5:0)
[02:35:35.551] Build status: SCHEDULED
[02:35:35.551] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:6:0) after 4m42s
[02:40:17.677] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:6:0)
[02:40:17.851] Build status: SCHEDULED
[02:40:17.851] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:7:0) after 6m21s
[02:46:38.870] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:7:0)
[02:46:38.885] Build status: SCHEDULED
[02:46:38.885] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:8:0) after 4m0s
[02:50:39.013] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:8:0)
[02:50:39.077] Build status: SCHEDULED
[02:50:39.077] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:9:0) after 7m35s
[02:58:14.092] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:9:0)
[02:58:14.126] Build status: SCHEDULED
[02:58:14.126] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:10:0) after 9m6s
[03:07:20.162] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:10:0)
[03:07:20.211] Build status: SCHEDULED
[03:07:20.211] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:11:0) after 3m48s
[03:11:08.432] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:11:0)
[03:11:08.451] Build status: SCHEDULED
[03:11:08.451] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:12:0) after 4m47s
[03:15:55.519] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:12:0)
[03:15:55.555] Build status: SCHEDULED
[03:15:55.555] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:13:0) after 3m57s
[03:19:52.869] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:13:0)
[03:19:52.898] Build status: SCHEDULED
[03:19:52.898] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:14:0) after 7m18s
[03:27:10.949] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:14:0)
[03:27:11.027] Build status: SCHEDULED
[03:27:11.027] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:15:0) after 8m44s
[03:35:55.179] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:15:0)
[03:35:55.203] Build status: SCHEDULED
[03:35:55.203] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:16:0) after 2m35s
[03:38:30.404] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:16:0)
[03:38:30.604] Build status: SCHEDULED
[03:38:30.604] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:17:0) after 4m42s
[03:43:12.599] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:17:0)
[03:43:12.632] Build status: SCHEDULED
[03:43:12.632] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:18:0) after 9m21s
[03:52:34.007] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:18:0)
[03:52:34.045] Build status: SCHEDULED
[03:52:34.045] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:19:0) after 4m38s
[03:57:12.061] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:19:0)
[03:57:12.187] Build status: SCHEDULED
[03:57:12.187] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:20:0) after 2m48s
[04:00:00.326] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:20:0)
[04:00:00.449] Build status: SCHEDULED
[04:00:00.449] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:21:0) after 6m41s
[04:06:41.465] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:21:0)
[04:06:41.489] Build status: SCHEDULED
[04:06:41.489] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:22:0) after 5m0s
[04:11:41.613] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:22:0)
[04:11:41.632] Build status: SCHEDULED
[04:11:41.632] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:23:0) after 2m20s
[04:14:01.633] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:23:0)
[04:14:01.678] Build status: SCHEDULED
[04:14:01.678] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:24:0) after 5m6s
[04:19:07.881] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:24:0)
[04:19:07.915] Build status: SCHEDULED
[04:19:07.915] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:25:0) after 1m14s
[04:20:22.254] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:25:0)
[04:20:22.288] Build status: SCHEDULED
[04:20:22.288] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:26:0) after 7m5s
[04:27:27.443] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:26:0)
[04:27:27.509] Build status: SCHEDULED
[04:27:27.509] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:27:0) after 6m22s
[04:33:49.628] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:27:0)
[04:33:49.649] Build status: SCHEDULED
[04:33:49.649] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:28:0) after 1m50s
[04:35:39.679] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:28:0)
[04:35:39.725] Build status: SCHEDULED
[04:35:39.725] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:29:0) after 1m37s
[04:37:16.744] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:29:0)
[04:37:16.765] Build status: SCHEDULED
[04:37:16.765] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:30:0) after 5m14s
[04:42:30.936] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:30:0)
[04:42:31.017] Build status: SCHEDULED
[04:42:31.017] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:31:0) after 3m33s
[04:46:04.050] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:31:0)
[04:46:04.116] Build status: SCHEDULED
[04:46:04.116] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:32:0) after 9m48s
[04:51:40.750] Received PubSub notification, asking Buildbucket for the build status
[04:51:40.828] Build status: STARTED
[04:55:52.443] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:32:0)
[04:55:52.485] Build status: STARTED
[04:55:52.485] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:34:0) after 3m11s
[04:59:03.566] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:34:0)
[04:59:03.587] Build status: STARTED
[04:59:03.587] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-full:8928470729156160400:35:0) after 9m30s
[05:01:11.873] Received PubSub notification, asking Buildbucket for the build status
[05:01:11.933] Build:
{
"id": "8763040666955196337",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-full"
},
"createdBy": "project:pigweed",
"createTime": "2023-11-30T02:10:04.509266870Z",
"startTime": "2023-11-30T04:51:39.661435Z",
"endTime": "2023-11-30T05:01:10.800093Z",
"updateTime": "2023-11-30T05:01:10.800093Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "03ec00caaffca5317a0df828b531dc0e2df8c0f4",
"ref": "refs/heads/main"
}
}
}
[05:01:11.933] Invocation finished in 2h51m8.906624399s with status SUCCEEDED