[00:24:46.906] New invocation is queued and will start shortly
[00:24:47.961] Starting the invocation (attempt 1)
[00:24:47.992] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a15ee8479c2c01b3cf49ad89031b6aa328a8f791"
[00:24:47.992] Popped gitiles commit info from properties and tags
[00:24:47.992] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:24:47.992] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:24:47.992] Buildbucket request:
{
"requestId": "8948861669540849328",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8948861669540849328",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e25e1044c928cdceca5d1b2d8e5dcb2c8d4d0fa1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e25e1044c928cdceca5d1b2d8e5dcb2c8d4d0fa1",
"title": "e25e1044c928cdceca5d1b2d8e5dcb2c8d4d0fa1",
"url": "https://fuchsia.googlesource.com/integration/+/e25e1044c928cdceca5d1b2d8e5dcb2c8d4d0fa1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ea39257ccf984e9fcf48ad88a5135cf0784668c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ea39257ccf984e9fcf48ad88a5135cf0784668c1",
"title": "ea39257ccf984e9fcf48ad88a5135cf0784668c1",
"url": "https://fuchsia.googlesource.com/integration/+/ea39257ccf984e9fcf48ad88a5135cf0784668c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "619668f2fb6932e7e18d70ae7fe7d9bac15f8deb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@619668f2fb6932e7e18d70ae7fe7d9bac15f8deb",
"title": "619668f2fb6932e7e18d70ae7fe7d9bac15f8deb",
"url": "https://fuchsia.googlesource.com/integration/+/619668f2fb6932e7e18d70ae7fe7d9bac15f8deb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f9cb984d37d83b18fcf6e7283f6a5fcb98f5e39"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f9cb984d37d83b18fcf6e7283f6a5fcb98f5e39",
"title": "0f9cb984d37d83b18fcf6e7283f6a5fcb98f5e39",
"url": "https://fuchsia.googlesource.com/integration/+/0f9cb984d37d83b18fcf6e7283f6a5fcb98f5e39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0ccc7e9d19988e4c7998644dcd3b3e57af3a8f9a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0ccc7e9d19988e4c7998644dcd3b3e57af3a8f9a",
"title": "0ccc7e9d19988e4c7998644dcd3b3e57af3a8f9a",
"url": "https://fuchsia.googlesource.com/integration/+/0ccc7e9d19988e4c7998644dcd3b3e57af3a8f9a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0c61fe6288055e6855d18c114d8a753ff665e1de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0c61fe6288055e6855d18c114d8a753ff665e1de",
"title": "0c61fe6288055e6855d18c114d8a753ff665e1de",
"url": "https://fuchsia.googlesource.com/integration/+/0c61fe6288055e6855d18c114d8a753ff665e1de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7053e8b7d65692a8d437319b7148d271852d71a1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7053e8b7d65692a8d437319b7148d271852d71a1",
"title": "7053e8b7d65692a8d437319b7148d271852d71a1",
"url": "https://fuchsia.googlesource.com/integration/+/7053e8b7d65692a8d437319b7148d271852d71a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dfd12011e509f937ef1abd2f243018baf4b20635"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dfd12011e509f937ef1abd2f243018baf4b20635",
"title": "dfd12011e509f937ef1abd2f243018baf4b20635",
"url": "https://fuchsia.googlesource.com/integration/+/dfd12011e509f937ef1abd2f243018baf4b20635"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dd716efa55f563378d331fec2ff0d4997bd2c57b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dd716efa55f563378d331fec2ff0d4997bd2c57b",
"title": "dd716efa55f563378d331fec2ff0d4997bd2c57b",
"url": "https://fuchsia.googlesource.com/integration/+/dd716efa55f563378d331fec2ff0d4997bd2c57b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a2dcc8eda0a910b4c6f7a7606dabe184e91bd5d2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a2dcc8eda0a910b4c6f7a7606dabe184e91bd5d2",
"title": "a2dcc8eda0a910b4c6f7a7606dabe184e91bd5d2",
"url": "https://fuchsia.googlesource.com/integration/+/a2dcc8eda0a910b4c6f7a7606dabe184e91bd5d2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a3e551de008d7e7496deb567994a32d8a713116"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a3e551de008d7e7496deb567994a32d8a713116",
"title": "0a3e551de008d7e7496deb567994a32d8a713116",
"url": "https://fuchsia.googlesource.com/integration/+/0a3e551de008d7e7496deb567994a32d8a713116"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a15ee8479c2c01b3cf49ad89031b6aa328a8f791"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a15ee8479c2c01b3cf49ad89031b6aa328a8f791",
"title": "a15ee8479c2c01b3cf49ad89031b6aa328a8f791",
"url": "https://fuchsia.googlesource.com/integration/+/a15ee8479c2c01b3cf49ad89031b6aa328a8f791"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a15ee8479c2c01b3cf49ad89031b6aa328a8f791",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8948861669540849328"
},
{
"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"
}
}
[00:24:48.328] Scheduled build:
{
"id": "8783431607664251217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-19T00:24:48.067345052Z",
"updateTime": "2023-04-19T00:24:48.067345052Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a15ee8479c2c01b3cf49ad89031b6aa328a8f791",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:24:48.328] Task URL: https://cr-buildbucket.appspot.com/build/8783431607664251217
[00:24:48.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:2:0) after 9m44s
[00:25:00.177] Received PubSub notification, asking Buildbucket for the build status
[00:25:00.202] Build status: STARTED
[00:34:32.537] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:2:0)
[00:34:32.611] Build status: STARTED
[00:34:32.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:4:0) after 7m32s
[00:42:04.919] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:4:0)
[00:42:04.946] Build status: STARTED
[00:42:04.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:5:0) after 7m34s
[00:49:39.387] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:5:0)
[00:49:39.407] Build status: STARTED
[00:49:39.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:6:0) after 6m15s
[00:55:54.657] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:6:0)
[00:55:54.699] Build status: STARTED
[00:55:54.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:7:0) after 4m24s
[01:00:19.522] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:7:0)
[01:00:19.547] Build status: STARTED
[01:00:19.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:8:0) after 3m31s
[01:03:50.574] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:8:0)
[01:03:50.606] Build status: STARTED
[01:03:50.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:9:0) after 2m44s
[01:06:34.758] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:9:0)
[01:06:34.785] Build status: STARTED
[01:06:34.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:10:0) after 5m20s
[01:11:55.331] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:10:0)
[01:11:55.355] Build status: STARTED
[01:11:55.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:11:0) after 6m19s
[01:18:14.688] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:11:0)
[01:18:14.728] Build status: STARTED
[01:18:14.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:12:0) after 8m5s
[01:26:20.060] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:12:0)
[01:26:20.082] Build status: STARTED
[01:26:20.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:13:0) after 8m16s
[01:34:36.102] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:13:0)
[01:34:36.125] Build status: STARTED
[01:34:36.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:14:0) after 2m31s
[01:37:07.142] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:14:0)
[01:37:07.165] Build status: STARTED
[01:37:07.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:15:0) after 1m12s
[01:38:19.222] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:15:0)
[01:38:19.281] Build status: STARTED
[01:38:19.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:16:0) after 6m24s
[01:44:43.481] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:16:0)
[01:44:43.503] Build status: STARTED
[01:44:43.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:17:0) after 3m33s
[01:48:16.497] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:17:0)
[01:48:16.516] Build status: STARTED
[01:48:16.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:18:0) after 3m23s
[01:51:39.669] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:18:0)
[01:51:39.689] Build status: STARTED
[01:51:39.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:19:0) after 4m12s
[01:55:51.786] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:19:0)
[01:55:51.806] Build status: STARTED
[01:55:51.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:20:0) after 2m4s
[01:57:55.825] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:20:0)
[01:57:55.850] Build status: STARTED
[01:57:55.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:21:0) after 5m29s
[02:03:24.965] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:21:0)
[02:03:24.986] Build status: STARTED
[02:03:24.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:22:0) after 8m26s
[02:11:51.078] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:22:0)
[02:11:51.100] Build status: STARTED
[02:11:51.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948861669540849328:23:0) after 5m43s
[02:15:00.811] Received PubSub notification, asking Buildbucket for the build status
[02:15:00.921] Build:
{
"id": "8783431607664251217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-19T00:24:48.067345052Z",
"startTime": "2023-04-19T00:24:59.713610Z",
"endTime": "2023-04-19T02:14:36.871138542Z",
"updateTime": "2023-04-19T02:14:36.871138542Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a15ee8479c2c01b3cf49ad89031b6aa328a8f791",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:15:00.921] Invocation finished in 1h50m14.027444506s with status SUCCEEDED