[22:33:19.303] New invocation is queued and will start shortly
[22:33:20.537] Starting the invocation (attempt 1)
[22:33:20.564] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/aaa51212beb9970cb0d1d3a2f496abc7f90e12b8"
[22:33:20.564] Popped gitiles commit info from properties and tags
[22:33:20.564] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:33:20.564] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:33:20.565] Buildbucket request:
{
"requestId": "8939174806596870176",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939174806596870176",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ab626440b1b04a437073c4d24c51a2a18799155b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ab626440b1b04a437073c4d24c51a2a18799155b",
"title": "ab626440b1b04a437073c4d24c51a2a18799155b",
"url": "https://fuchsia.googlesource.com/integration/+/ab626440b1b04a437073c4d24c51a2a18799155b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e1af572fe7ad8f0672c2e1b32286865fcf5a608"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e1af572fe7ad8f0672c2e1b32286865fcf5a608",
"title": "8e1af572fe7ad8f0672c2e1b32286865fcf5a608",
"url": "https://fuchsia.googlesource.com/integration/+/8e1af572fe7ad8f0672c2e1b32286865fcf5a608"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "84006357f596188ebe8c2cbe89e3fb0135be19c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@84006357f596188ebe8c2cbe89e3fb0135be19c3",
"title": "84006357f596188ebe8c2cbe89e3fb0135be19c3",
"url": "https://fuchsia.googlesource.com/integration/+/84006357f596188ebe8c2cbe89e3fb0135be19c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "21209fa848f15e4b1e085eafd2242c689825c580"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@21209fa848f15e4b1e085eafd2242c689825c580",
"title": "21209fa848f15e4b1e085eafd2242c689825c580",
"url": "https://fuchsia.googlesource.com/integration/+/21209fa848f15e4b1e085eafd2242c689825c580"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7587935ffb8b9d948a94adbcf20cd8e968f8003"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7587935ffb8b9d948a94adbcf20cd8e968f8003",
"title": "e7587935ffb8b9d948a94adbcf20cd8e968f8003",
"url": "https://fuchsia.googlesource.com/integration/+/e7587935ffb8b9d948a94adbcf20cd8e968f8003"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f4a87107d945fa37201d66d341ce06e8f4727ad2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f4a87107d945fa37201d66d341ce06e8f4727ad2",
"title": "f4a87107d945fa37201d66d341ce06e8f4727ad2",
"url": "https://fuchsia.googlesource.com/integration/+/f4a87107d945fa37201d66d341ce06e8f4727ad2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "82da891d3a200cdb8879022a5f8bac153ae8765e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@82da891d3a200cdb8879022a5f8bac153ae8765e",
"title": "82da891d3a200cdb8879022a5f8bac153ae8765e",
"url": "https://fuchsia.googlesource.com/integration/+/82da891d3a200cdb8879022a5f8bac153ae8765e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "274958e4b5e661202a1200371e7093c1a817ea31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@274958e4b5e661202a1200371e7093c1a817ea31",
"title": "274958e4b5e661202a1200371e7093c1a817ea31",
"url": "https://fuchsia.googlesource.com/integration/+/274958e4b5e661202a1200371e7093c1a817ea31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b40c8c05f32c891adcf45e7929eed425547ef52f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b40c8c05f32c891adcf45e7929eed425547ef52f",
"title": "b40c8c05f32c891adcf45e7929eed425547ef52f",
"url": "https://fuchsia.googlesource.com/integration/+/b40c8c05f32c891adcf45e7929eed425547ef52f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2de0e37e9c823e8f4d401ff35c395edbef8a0100"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2de0e37e9c823e8f4d401ff35c395edbef8a0100",
"title": "2de0e37e9c823e8f4d401ff35c395edbef8a0100",
"url": "https://fuchsia.googlesource.com/integration/+/2de0e37e9c823e8f4d401ff35c395edbef8a0100"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f7dbe94263172f4ac09d27b624aa1452ec59a143"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f7dbe94263172f4ac09d27b624aa1452ec59a143",
"title": "f7dbe94263172f4ac09d27b624aa1452ec59a143",
"url": "https://fuchsia.googlesource.com/integration/+/f7dbe94263172f4ac09d27b624aa1452ec59a143"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9c4561992fb8cbc7ad4b6196c49b9c96702ff1e6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9c4561992fb8cbc7ad4b6196c49b9c96702ff1e6",
"title": "9c4561992fb8cbc7ad4b6196c49b9c96702ff1e6",
"url": "https://fuchsia.googlesource.com/integration/+/9c4561992fb8cbc7ad4b6196c49b9c96702ff1e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "14436736237fa175ddb6de5d565c27b901a263c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@14436736237fa175ddb6de5d565c27b901a263c1",
"title": "14436736237fa175ddb6de5d565c27b901a263c1",
"url": "https://fuchsia.googlesource.com/integration/+/14436736237fa175ddb6de5d565c27b901a263c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aaa51212beb9970cb0d1d3a2f496abc7f90e12b8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aaa51212beb9970cb0d1d3a2f496abc7f90e12b8",
"title": "aaa51212beb9970cb0d1d3a2f496abc7f90e12b8",
"url": "https://fuchsia.googlesource.com/integration/+/aaa51212beb9970cb0d1d3a2f496abc7f90e12b8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "aaa51212beb9970cb0d1d3a2f496abc7f90e12b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939174806596870176"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:33:21.051] Scheduled build:
{
"id": "8773744744553390769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-03T22:33:20.623114157Z",
"updateTime": "2023-08-03T22:33:20.623114157Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "aaa51212beb9970cb0d1d3a2f496abc7f90e12b8",
"ref": "refs/heads/main"
}
}
}
[22:33:21.051] Task URL: https://cr-buildbucket.appspot.com/build/8773744744553390769
[22:33:21.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:2:0) after 6m0s
[22:33:22.136] Received PubSub notification, asking Buildbucket for the build status
[22:33:22.164] Build status: SCHEDULED
[22:33:58.393] Received PubSub notification, asking Buildbucket for the build status
[22:33:58.424] Build status: STARTED
[22:39:21.045] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:2:0)
[22:39:21.071] Build status: STARTED
[22:39:21.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:5:0) after 2m56s
[22:42:17.274] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:5:0)
[22:42:17.297] Build status: STARTED
[22:42:17.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:6:0) after 7m4s
[22:49:21.536] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:6:0)
[22:49:21.561] Build status: STARTED
[22:49:21.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:7:0) after 7m21s
[22:56:42.682] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:7:0)
[22:56:42.754] Build status: STARTED
[22:56:42.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:8:0) after 7m17s
[23:03:59.875] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:8:0)
[23:03:59.897] Build status: STARTED
[23:03:59.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:9:0) after 3m51s
[23:07:50.933] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:9:0)
[23:07:50.954] Build status: STARTED
[23:07:50.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:10:0) after 8m16s
[23:16:06.974] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:10:0)
[23:16:07.064] Build status: STARTED
[23:16:07.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:11:0) after 8m16s
[23:24:23.081] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:11:0)
[23:24:23.098] Build status: STARTED
[23:24:23.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:12:0) after 5m59s
[23:30:22.156] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:12:0)
[23:30:22.190] Build status: STARTED
[23:30:22.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:13:0) after 5m13s
[23:35:35.209] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:13:0)
[23:35:35.241] Build status: STARTED
[23:35:35.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:14:0) after 8m44s
[23:44:19.365] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:14:0)
[23:44:19.385] Build status: STARTED
[23:44:19.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:15:0) after 8m22s
[23:52:41.505] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:15:0)
[23:52:41.533] Build status: STARTED
[23:52:41.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939174806596870176:16:0) after 9m5s
[23:53:26.518] Received PubSub notification, asking Buildbucket for the build status
[23:53:26.540] Build:
{
"id": "8773744744553390769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-03T22:33:20.623114157Z",
"startTime": "2023-08-03T22:33:57.846506Z",
"endTime": "2023-08-03T23:53:25.883308024Z",
"updateTime": "2023-08-03T23:53:25.883308024Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "aaa51212beb9970cb0d1d3a2f496abc7f90e12b8",
"ref": "refs/heads/main"
}
}
}
[23:53:26.540] Invocation finished in 1h20m7.249514894s with status FAILED