[03:06:26.437] New invocation is queued and will start shortly
[03:06:27.657] Starting the invocation (attempt 1)
[03:06:27.685] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/4e26fa470261a28d031166996e3fb7b808a456c3"
[03:06:27.685] Popped gitiles commit info from properties and tags
[03:06:27.685] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:06:27.685] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:06:27.685] Buildbucket request:
{
"requestId": "8929826135896112992",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8929826135896112992",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "168ceb37379d10eb3880ea865a4d7b19efe2c192"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@168ceb37379d10eb3880ea865a4d7b19efe2c192",
"title": "168ceb37379d10eb3880ea865a4d7b19efe2c192",
"url": "https://fuchsia.googlesource.com/integration/+/168ceb37379d10eb3880ea865a4d7b19efe2c192"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9af4233201e846e00dd55bdd912b0e255aae063c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9af4233201e846e00dd55bdd912b0e255aae063c",
"title": "9af4233201e846e00dd55bdd912b0e255aae063c",
"url": "https://fuchsia.googlesource.com/integration/+/9af4233201e846e00dd55bdd912b0e255aae063c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6d0a76f3ca406f1ef66efb79b97598863298da1f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d0a76f3ca406f1ef66efb79b97598863298da1f",
"title": "6d0a76f3ca406f1ef66efb79b97598863298da1f",
"url": "https://fuchsia.googlesource.com/integration/+/6d0a76f3ca406f1ef66efb79b97598863298da1f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8413df6e57e8b19a0dada0304d50f2a2d534acaf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8413df6e57e8b19a0dada0304d50f2a2d534acaf",
"title": "8413df6e57e8b19a0dada0304d50f2a2d534acaf",
"url": "https://fuchsia.googlesource.com/integration/+/8413df6e57e8b19a0dada0304d50f2a2d534acaf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "442cef9dcf764c3ca99aceff876ea544582a5982"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@442cef9dcf764c3ca99aceff876ea544582a5982",
"title": "442cef9dcf764c3ca99aceff876ea544582a5982",
"url": "https://fuchsia.googlesource.com/integration/+/442cef9dcf764c3ca99aceff876ea544582a5982"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9cb01fb8add6b42661ec998b37d1f24ac734793e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9cb01fb8add6b42661ec998b37d1f24ac734793e",
"title": "9cb01fb8add6b42661ec998b37d1f24ac734793e",
"url": "https://fuchsia.googlesource.com/integration/+/9cb01fb8add6b42661ec998b37d1f24ac734793e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "25cccec71216190c6b8e2d37c72368def3be026a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@25cccec71216190c6b8e2d37c72368def3be026a",
"title": "25cccec71216190c6b8e2d37c72368def3be026a",
"url": "https://fuchsia.googlesource.com/integration/+/25cccec71216190c6b8e2d37c72368def3be026a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1090595dfa6cba8a6fc483dfcfe50717ca7c97e9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1090595dfa6cba8a6fc483dfcfe50717ca7c97e9",
"title": "1090595dfa6cba8a6fc483dfcfe50717ca7c97e9",
"url": "https://fuchsia.googlesource.com/integration/+/1090595dfa6cba8a6fc483dfcfe50717ca7c97e9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e26fa470261a28d031166996e3fb7b808a456c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e26fa470261a28d031166996e3fb7b808a456c3",
"title": "4e26fa470261a28d031166996e3fb7b808a456c3",
"url": "https://fuchsia.googlesource.com/integration/+/4e26fa470261a28d031166996e3fb7b808a456c3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4e26fa470261a28d031166996e3fb7b808a456c3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929826135896112992"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/firebase-docs-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:06:28.329] Scheduled build:
{
"id": "8764396073779070993",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T03:06:27.833753351Z",
"updateTime": "2023-11-15T03:06:27.833753351Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4e26fa470261a28d031166996e3fb7b808a456c3",
"ref": "refs/heads/main"
}
}
}
[03:06:28.329] Task URL: https://cr-buildbucket.appspot.com/build/8764396073779070993
[03:06:28.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:2:0) after 3m59s
[03:06:29.286] Received PubSub notification, asking Buildbucket for the build status
[03:06:29.308] Build status: SCHEDULED
[03:08:29.284] Received PubSub notification, asking Buildbucket for the build status
[03:08:29.305] Build status: STARTED
[03:10:27.346] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:2:0)
[03:10:27.373] Build status: STARTED
[03:10:27.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:5:0) after 3m11s
[03:13:38.396] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:5:0)
[03:13:38.478] Build status: STARTED
[03:13:38.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:6:0) after 3m24s
[03:17:02.560] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:6:0)
[03:17:02.746] Build status: STARTED
[03:17:02.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:7:0) after 1m48s
[03:18:50.813] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:7:0)
[03:18:50.843] Build status: STARTED
[03:18:50.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:8:0) after 7m28s
[03:26:19.228] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:8:0)
[03:26:19.266] Build status: STARTED
[03:26:19.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:9:0) after 2m19s
[03:28:38.286] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:9:0)
[03:28:38.309] Build status: STARTED
[03:28:38.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:10:0) after 1m35s
[03:30:13.356] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:10:0)
[03:30:13.467] Build status: STARTED
[03:30:13.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:11:0) after 2m11s
[03:32:24.484] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:11:0)
[03:32:24.514] Build status: STARTED
[03:32:24.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:12:0) after 7m19s
[03:39:43.771] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:12:0)
[03:39:43.790] Build status: STARTED
[03:39:43.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:13:0) after 8m6s
[03:47:49.869] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:13:0)
[03:47:49.898] Build status: STARTED
[03:47:49.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:14:0) after 6m44s
[03:54:34.325] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:14:0)
[03:54:34.347] Build status: STARTED
[03:54:34.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:15:0) after 6m20s
[04:00:54.379] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:15:0)
[04:00:54.401] Build status: STARTED
[04:00:54.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:16:0) after 1m27s
[04:02:21.891] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:16:0)
[04:02:21.924] Build status: STARTED
[04:02:21.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:17:0) after 4m37s
[04:06:58.949] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:17:0)
[04:06:59.093] Build status: STARTED
[04:06:59.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:18:0) after 9m29s
[04:16:28.307] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:18:0)
[04:16:28.331] Build status: STARTED
[04:16:28.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:19:0) after 9m57s
[04:26:25.534] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:19:0)
[04:26:25.571] Build status: STARTED
[04:26:25.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:20:0) after 2m23s
[04:28:48.677] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:20:0)
[04:28:48.856] Build status: STARTED
[04:28:48.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:21:0) after 1m25s
[04:30:13.966] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:21:0)
[04:30:13.994] Build status: STARTED
[04:30:13.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:22:0) after 4m12s
[04:34:26.281] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:22:0)
[04:34:26.298] Build status: STARTED
[04:34:26.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:23:0) after 2m10s
[04:36:36.369] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:23:0)
[04:36:36.406] Build status: STARTED
[04:36:36.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:24:0) after 9m43s
[04:46:19.800] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:24:0)
[04:46:19.885] Build status: STARTED
[04:46:19.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:25:0) after 8m45s
[04:55:05.224] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:25:0)
[04:55:05.370] Build status: STARTED
[04:55:05.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:26:0) after 5m23s
[05:00:28.391] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:26:0)
[05:00:28.418] Build status: STARTED
[05:00:28.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:27:0) after 8m28s
[05:08:56.439] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:27:0)
[05:08:56.462] Build status: STARTED
[05:08:56.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:28:0) after 1m18s
[05:10:14.591] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:28:0)
[05:10:14.736] Build status: STARTED
[05:10:14.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:29:0) after 4m9s
[05:14:23.760] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:29:0)
[05:14:23.791] Build status: STARTED
[05:14:23.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929826135896112992:30:0) after 7m28s
[05:18:23.747] Received PubSub notification, asking Buildbucket for the build status
[05:18:23.766] Build:
{
"id": "8764396073779070993",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T03:06:27.833753351Z",
"startTime": "2023-11-15T03:08:28.847871Z",
"endTime": "2023-11-15T05:18:22.851114Z",
"updateTime": "2023-11-15T05:18:22.851114Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4e26fa470261a28d031166996e3fb7b808a456c3",
"ref": "refs/heads/main"
}
}
}
[05:18:23.766] Invocation finished in 2h11m57.336376591s with status SUCCEEDED