[01:39:26.761] New invocation is queued and will start shortly
[01:39:27.855] Starting the invocation (attempt 1)
[01:39:27.883] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/201d07a69d2e74879dcaa155c6c126d7584ce250"
[01:39:27.883] Popped gitiles commit info from properties and tags
[01:39:27.883] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:27.883] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:27.883] Buildbucket request:
{
"requestId": "8939434887566396304",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939434887566396304",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "718d9c938718c18decc78f7a46fdd79bd337b01a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@718d9c938718c18decc78f7a46fdd79bd337b01a",
"title": "718d9c938718c18decc78f7a46fdd79bd337b01a",
"url": "https://fuchsia.googlesource.com/integration/+/718d9c938718c18decc78f7a46fdd79bd337b01a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a0f89ccb64c81081dec2b11aa97fbd5d030bdc4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a0f89ccb64c81081dec2b11aa97fbd5d030bdc4",
"title": "8a0f89ccb64c81081dec2b11aa97fbd5d030bdc4",
"url": "https://fuchsia.googlesource.com/integration/+/8a0f89ccb64c81081dec2b11aa97fbd5d030bdc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "92981073a2bc0f46acc0f9819f4c36ae31721628"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@92981073a2bc0f46acc0f9819f4c36ae31721628",
"title": "92981073a2bc0f46acc0f9819f4c36ae31721628",
"url": "https://fuchsia.googlesource.com/integration/+/92981073a2bc0f46acc0f9819f4c36ae31721628"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b41d2ba67a0b0d6069f2dd0c4e6dcca2c1ef0b25"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b41d2ba67a0b0d6069f2dd0c4e6dcca2c1ef0b25",
"title": "b41d2ba67a0b0d6069f2dd0c4e6dcca2c1ef0b25",
"url": "https://fuchsia.googlesource.com/integration/+/b41d2ba67a0b0d6069f2dd0c4e6dcca2c1ef0b25"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "00956e8c82832efb813e237869ace13593e2fa7a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@00956e8c82832efb813e237869ace13593e2fa7a",
"title": "00956e8c82832efb813e237869ace13593e2fa7a",
"url": "https://fuchsia.googlesource.com/integration/+/00956e8c82832efb813e237869ace13593e2fa7a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee660b3a426a292bc16fc52345bb12bee7faf0f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee660b3a426a292bc16fc52345bb12bee7faf0f1",
"title": "ee660b3a426a292bc16fc52345bb12bee7faf0f1",
"url": "https://fuchsia.googlesource.com/integration/+/ee660b3a426a292bc16fc52345bb12bee7faf0f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bfae7f391bc2491d5081e7e59ae98017c4105499"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bfae7f391bc2491d5081e7e59ae98017c4105499",
"title": "bfae7f391bc2491d5081e7e59ae98017c4105499",
"url": "https://fuchsia.googlesource.com/integration/+/bfae7f391bc2491d5081e7e59ae98017c4105499"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30f2c4e1faf92e628de87aea9aecf86993377e0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30f2c4e1faf92e628de87aea9aecf86993377e0a",
"title": "30f2c4e1faf92e628de87aea9aecf86993377e0a",
"url": "https://fuchsia.googlesource.com/integration/+/30f2c4e1faf92e628de87aea9aecf86993377e0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3383a3322aaff0e14a744545930b3bf8aaa5ec82"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3383a3322aaff0e14a744545930b3bf8aaa5ec82",
"title": "3383a3322aaff0e14a744545930b3bf8aaa5ec82",
"url": "https://fuchsia.googlesource.com/integration/+/3383a3322aaff0e14a744545930b3bf8aaa5ec82"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "98b54b0bdacd10d3b720fe368e50b7a3496eb58a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@98b54b0bdacd10d3b720fe368e50b7a3496eb58a",
"title": "98b54b0bdacd10d3b720fe368e50b7a3496eb58a",
"url": "https://fuchsia.googlesource.com/integration/+/98b54b0bdacd10d3b720fe368e50b7a3496eb58a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "201d07a69d2e74879dcaa155c6c126d7584ce250"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@201d07a69d2e74879dcaa155c6c126d7584ce250",
"title": "201d07a69d2e74879dcaa155c6c126d7584ce250",
"url": "https://fuchsia.googlesource.com/integration/+/201d07a69d2e74879dcaa155c6c126d7584ce250"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "201d07a69d2e74879dcaa155c6c126d7584ce250",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939434887566396304"
},
{
"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"
}
}
[01:39:28.271] Scheduled build:
{
"id": "8774004825675611217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-01T01:39:27.936219243Z",
"updateTime": "2023-08-01T01:39:27.936219243Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "201d07a69d2e74879dcaa155c6c126d7584ce250",
"ref": "refs/heads/main"
}
}
}
[01:39:28.271] Task URL: https://cr-buildbucket.appspot.com/build/8774004825675611217
[01:39:28.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:2:0) after 8m38s
[01:39:29.218] Received PubSub notification, asking Buildbucket for the build status
[01:39:29.258] Build status: SCHEDULED
[01:39:49.769] Received PubSub notification, asking Buildbucket for the build status
[01:39:49.859] Build status: STARTED
[01:48:06.296] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:2:0)
[01:48:06.316] Build status: STARTED
[01:48:06.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:5:0) after 8m8s
[01:56:14.343] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:5:0)
[01:56:14.366] Build status: STARTED
[01:56:14.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:6:0) after 5m23s
[02:01:37.672] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:6:0)
[02:01:37.692] Build status: STARTED
[02:01:37.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:7:0) after 6m3s
[02:07:40.749] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:7:0)
[02:07:40.766] Build status: STARTED
[02:07:40.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:8:0) after 7m5s
[02:14:45.785] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:8:0)
[02:14:45.859] Build status: STARTED
[02:14:45.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:9:0) after 1m0s
[02:15:46.038] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:9:0)
[02:15:46.059] Build status: STARTED
[02:15:46.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:10:0) after 9m15s
[02:25:01.185] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:10:0)
[02:25:01.206] Build status: STARTED
[02:25:01.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:11:0) after 1m6s
[02:26:07.342] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:11:0)
[02:26:07.413] Build status: STARTED
[02:26:07.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:12:0) after 6m5s
[02:32:12.488] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:12:0)
[02:32:12.513] Build status: STARTED
[02:32:12.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:13:0) after 7m52s
[02:40:04.620] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:13:0)
[02:40:04.638] Build status: STARTED
[02:40:04.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:14:0) after 6m7s
[02:46:11.724] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:14:0)
[02:46:11.769] Build status: STARTED
[02:46:11.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:15:0) after 9m5s
[02:55:16.789] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:15:0)
[02:55:16.809] Build status: STARTED
[02:55:16.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:16:0) after 6m36s
[03:01:52.837] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:16:0)
[03:01:52.859] Build status: STARTED
[03:01:52.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939434887566396304:17:0) after 7m14s
[03:04:19.280] Received PubSub notification, asking Buildbucket for the build status
[03:04:19.381] Build:
{
"id": "8774004825675611217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-01T01:39:27.936219243Z",
"startTime": "2023-08-01T01:39:48.903386Z",
"endTime": "2023-08-01T03:04:18.875066613Z",
"updateTime": "2023-08-01T03:04:18.875066613Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "201d07a69d2e74879dcaa155c6c126d7584ce250",
"ref": "refs/heads/main"
}
}
}
[03:04:19.381] Invocation finished in 1h24m52.632327383s with status FAILED