[19:29:48.463] New invocation is queued and will start shortly
[19:29:49.513] Starting the invocation (attempt 1)
[19:29:49.546] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/9f87e81b4f5e90febac0aecc204f77cd4724a766"
[19:29:49.546] Popped gitiles commit info from properties and tags
[19:29:49.546] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:29:49.548] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:29:49.548] Buildbucket request:
{
"requestId": "9004416168109860272",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-docs-roller"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6a1c08f29f8d5a56d1b40549554004d4b03b5037"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6a1c08f29f8d5a56d1b40549554004d4b03b5037",
"title": "6a1c08f29f8d5a56d1b40549554004d4b03b5037",
"url": "https://fuchsia.googlesource.com/integration/+/6a1c08f29f8d5a56d1b40549554004d4b03b5037"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "58a67e24a4019cb9c89be304c890f089f9e259bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@58a67e24a4019cb9c89be304c890f089f9e259bc",
"title": "58a67e24a4019cb9c89be304c890f089f9e259bc",
"url": "https://fuchsia.googlesource.com/integration/+/58a67e24a4019cb9c89be304c890f089f9e259bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3cead93230a55446ed57dbda232cd48fb045f939"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3cead93230a55446ed57dbda232cd48fb045f939",
"title": "3cead93230a55446ed57dbda232cd48fb045f939",
"url": "https://fuchsia.googlesource.com/integration/+/3cead93230a55446ed57dbda232cd48fb045f939"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "19071df7446a3e87b1414683c6d9f0c065657c44"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@19071df7446a3e87b1414683c6d9f0c065657c44",
"title": "19071df7446a3e87b1414683c6d9f0c065657c44",
"url": "https://fuchsia.googlesource.com/integration/+/19071df7446a3e87b1414683c6d9f0c065657c44"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "90aafc4e09ae1f33b0ecb3d6c1d7ecb9dc81bcf6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@90aafc4e09ae1f33b0ecb3d6c1d7ecb9dc81bcf6",
"title": "90aafc4e09ae1f33b0ecb3d6c1d7ecb9dc81bcf6",
"url": "https://fuchsia.googlesource.com/integration/+/90aafc4e09ae1f33b0ecb3d6c1d7ecb9dc81bcf6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "05dad06f4e4f17739bc928cdb9752f912fa6dda9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@05dad06f4e4f17739bc928cdb9752f912fa6dda9",
"title": "05dad06f4e4f17739bc928cdb9752f912fa6dda9",
"url": "https://fuchsia.googlesource.com/integration/+/05dad06f4e4f17739bc928cdb9752f912fa6dda9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a3f19fbf85a89d8c07b50aace798e77ebb91e53a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a3f19fbf85a89d8c07b50aace798e77ebb91e53a",
"title": "a3f19fbf85a89d8c07b50aace798e77ebb91e53a",
"url": "https://fuchsia.googlesource.com/integration/+/a3f19fbf85a89d8c07b50aace798e77ebb91e53a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "424293b05a7d84de852447bc438b1520365d0cfe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@424293b05a7d84de852447bc438b1520365d0cfe",
"title": "424293b05a7d84de852447bc438b1520365d0cfe",
"url": "https://fuchsia.googlesource.com/integration/+/424293b05a7d84de852447bc438b1520365d0cfe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "429a4dd9f68e744d5c2065ea41ba2b2009ee7f52"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@429a4dd9f68e744d5c2065ea41ba2b2009ee7f52",
"title": "429a4dd9f68e744d5c2065ea41ba2b2009ee7f52",
"url": "https://fuchsia.googlesource.com/integration/+/429a4dd9f68e744d5c2065ea41ba2b2009ee7f52"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2aa9d335e9c34110dcc071852f5f6727de53e92b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2aa9d335e9c34110dcc071852f5f6727de53e92b",
"title": "2aa9d335e9c34110dcc071852f5f6727de53e92b",
"url": "https://fuchsia.googlesource.com/integration/+/2aa9d335e9c34110dcc071852f5f6727de53e92b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2336cc372360ec7bc07b90d936a0d5c417d43c3a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2336cc372360ec7bc07b90d936a0d5c417d43c3a",
"title": "2336cc372360ec7bc07b90d936a0d5c417d43c3a",
"url": "https://fuchsia.googlesource.com/integration/+/2336cc372360ec7bc07b90d936a0d5c417d43c3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22780db58a659b7a8b011cac7f3d9d363183bfb3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22780db58a659b7a8b011cac7f3d9d363183bfb3",
"title": "22780db58a659b7a8b011cac7f3d9d363183bfb3",
"url": "https://fuchsia.googlesource.com/integration/+/22780db58a659b7a8b011cac7f3d9d363183bfb3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9f87e81b4f5e90febac0aecc204f77cd4724a766"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9f87e81b4f5e90febac0aecc204f77cd4724a766",
"title": "9f87e81b4f5e90febac0aecc204f77cd4724a766",
"url": "https://fuchsia.googlesource.com/integration/+/9f87e81b4f5e90febac0aecc204f77cd4724a766"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9f87e81b4f5e90febac0aecc204f77cd4724a766",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9004416168109860272"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-docs-roller-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:29:50.082] Scheduled build:
{
"id": "8838986106201441360",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-docs-roller"
},
"createdBy": "project:fuchsia",
"createTime": "2021-08-13T19:29:49.651548Z",
"updateTime": "2021-08-13T19:29:49.975152Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9f87e81b4f5e90febac0aecc204f77cd4724a766",
"ref": "refs/heads/main"
}
}
}
[19:29:50.082] Task URL: https://cr-buildbucket.appspot.com/build/8838986106201441360
[19:29:50.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:2:0) after 2m14s
[19:32:04.178] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:2:0)
[19:32:04.306] Build status: SCHEDULED
[19:32:04.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:3:0) after 6m23s
[19:32:33.110] Received PubSub notification, asking Buildbucket for the build status
[19:32:33.134] Build status: STARTED
[19:38:27.415] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:3:0)
[19:38:27.444] Build status: STARTED
[19:38:27.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:5:0) after 7m54s
[19:46:21.466] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:5:0)
[19:46:21.503] Build status: STARTED
[19:46:21.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:6:0) after 1m26s
[19:47:47.532] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:6:0)
[19:47:47.568] Build status: STARTED
[19:47:47.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:7:0) after 7m17s
[19:55:04.641] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:7:0)
[19:55:04.682] Build status: STARTED
[19:55:04.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:8:0) after 7m4s
[20:02:08.928] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:8:0)
[20:02:08.966] Build status: STARTED
[20:02:08.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:9:0) after 1m31s
[20:03:39.991] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:9:0)
[20:03:40.023] Build status: STARTED
[20:03:40.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:10:0) after 7m35s
[20:11:15.281] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:10:0)
[20:11:15.311] Build status: STARTED
[20:11:15.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:11:0) after 6m12s
[20:17:27.335] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:11:0)
[20:17:27.363] Build status: STARTED
[20:17:27.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:12:0) after 8m44s
[20:26:11.420] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:12:0)
[20:26:11.450] Build status: STARTED
[20:26:11.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-docs-roller-e5a0ec7f:9004416168109860272:13:0) after 7m52s
[20:27:22.191] Received PubSub notification, asking Buildbucket for the build status
[20:27:22.277] Build:
{
"id": "8838986106201441360",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-docs-roller"
},
"createdBy": "project:fuchsia",
"createTime": "2021-08-13T19:29:49.651548Z",
"startTime": "2021-08-13T19:32:25.547384Z",
"endTime": "2021-08-13T20:27:21.869968550Z",
"updateTime": "2021-08-13T20:27:21.870390745Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9f87e81b4f5e90febac0aecc204f77cd4724a766",
"ref": "refs/heads/main"
}
}
}
[20:27:22.277] Invocation finished in 57m33.828981821s with status FAILED