[19:17:54.154] New invocation is queued and will start shortly
[19:17:55.224] Starting the invocation (attempt 1)
[19:17:55.251] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/206f30adff68d2f203d0c6caf5b34045baf56ea9"
[19:17:55.251] Popped gitiles commit info from properties and tags
[19:17:55.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:17:55.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:17:55.251] Buildbucket request:
{
"requestId": "8937465758939856816",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937465758939856816",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "89580552bd207f7255b91692a542bc7ac0cc5114"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@89580552bd207f7255b91692a542bc7ac0cc5114",
"title": "89580552bd207f7255b91692a542bc7ac0cc5114",
"url": "https://fuchsia.googlesource.com/integration/+/89580552bd207f7255b91692a542bc7ac0cc5114"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ce2b1d33fdb222087b5fd0e9831a784807e0029f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ce2b1d33fdb222087b5fd0e9831a784807e0029f",
"title": "ce2b1d33fdb222087b5fd0e9831a784807e0029f",
"url": "https://fuchsia.googlesource.com/integration/+/ce2b1d33fdb222087b5fd0e9831a784807e0029f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b44aeb2b4407620a29677f8b34f503993bd6dd5a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b44aeb2b4407620a29677f8b34f503993bd6dd5a",
"title": "b44aeb2b4407620a29677f8b34f503993bd6dd5a",
"url": "https://fuchsia.googlesource.com/integration/+/b44aeb2b4407620a29677f8b34f503993bd6dd5a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a6754c2201b80bb28fbd20a068571add5ad32dd0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a6754c2201b80bb28fbd20a068571add5ad32dd0",
"title": "a6754c2201b80bb28fbd20a068571add5ad32dd0",
"url": "https://fuchsia.googlesource.com/integration/+/a6754c2201b80bb28fbd20a068571add5ad32dd0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "86194622737d9fd97e2fe8ddf0d87bf47018451c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@86194622737d9fd97e2fe8ddf0d87bf47018451c",
"title": "86194622737d9fd97e2fe8ddf0d87bf47018451c",
"url": "https://fuchsia.googlesource.com/integration/+/86194622737d9fd97e2fe8ddf0d87bf47018451c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d24852b04963bcf5de4dd185a6e33444f53f6e98"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d24852b04963bcf5de4dd185a6e33444f53f6e98",
"title": "d24852b04963bcf5de4dd185a6e33444f53f6e98",
"url": "https://fuchsia.googlesource.com/integration/+/d24852b04963bcf5de4dd185a6e33444f53f6e98"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4872e81461966f9c2d157066d18220458e610458"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4872e81461966f9c2d157066d18220458e610458",
"title": "4872e81461966f9c2d157066d18220458e610458",
"url": "https://fuchsia.googlesource.com/integration/+/4872e81461966f9c2d157066d18220458e610458"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bdf66a8ce504985ed6bf361266f3bc97fa0cfb0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bdf66a8ce504985ed6bf361266f3bc97fa0cfb0a",
"title": "bdf66a8ce504985ed6bf361266f3bc97fa0cfb0a",
"url": "https://fuchsia.googlesource.com/integration/+/bdf66a8ce504985ed6bf361266f3bc97fa0cfb0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6d98d12fcf2ad87afa2573566a545ca64fa973d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6d98d12fcf2ad87afa2573566a545ca64fa973d",
"title": "d6d98d12fcf2ad87afa2573566a545ca64fa973d",
"url": "https://fuchsia.googlesource.com/integration/+/d6d98d12fcf2ad87afa2573566a545ca64fa973d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb72aa3b205e14c30e7b6602af5dcb1b3ef3d307"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb72aa3b205e14c30e7b6602af5dcb1b3ef3d307",
"title": "fb72aa3b205e14c30e7b6602af5dcb1b3ef3d307",
"url": "https://fuchsia.googlesource.com/integration/+/fb72aa3b205e14c30e7b6602af5dcb1b3ef3d307"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "25bb6d1b43f515d55670d9e4460888c6927c2f09"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@25bb6d1b43f515d55670d9e4460888c6927c2f09",
"title": "25bb6d1b43f515d55670d9e4460888c6927c2f09",
"url": "https://fuchsia.googlesource.com/integration/+/25bb6d1b43f515d55670d9e4460888c6927c2f09"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50a1b558424297acf8edb93df0603ec4ba048a7d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50a1b558424297acf8edb93df0603ec4ba048a7d",
"title": "50a1b558424297acf8edb93df0603ec4ba048a7d",
"url": "https://fuchsia.googlesource.com/integration/+/50a1b558424297acf8edb93df0603ec4ba048a7d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3aa440ad4c3a99d6222421fdc1c8af7f7e6903ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3aa440ad4c3a99d6222421fdc1c8af7f7e6903ba",
"title": "3aa440ad4c3a99d6222421fdc1c8af7f7e6903ba",
"url": "https://fuchsia.googlesource.com/integration/+/3aa440ad4c3a99d6222421fdc1c8af7f7e6903ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "484241b9475e46b209d484e491c54a0e329ac09c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@484241b9475e46b209d484e491c54a0e329ac09c",
"title": "484241b9475e46b209d484e491c54a0e329ac09c",
"url": "https://fuchsia.googlesource.com/integration/+/484241b9475e46b209d484e491c54a0e329ac09c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d51e8f8f7786c06dbe8b9ac9a9110b430ee8380"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d51e8f8f7786c06dbe8b9ac9a9110b430ee8380",
"title": "2d51e8f8f7786c06dbe8b9ac9a9110b430ee8380",
"url": "https://fuchsia.googlesource.com/integration/+/2d51e8f8f7786c06dbe8b9ac9a9110b430ee8380"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "206f30adff68d2f203d0c6caf5b34045baf56ea9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@206f30adff68d2f203d0c6caf5b34045baf56ea9",
"title": "206f30adff68d2f203d0c6caf5b34045baf56ea9",
"url": "https://fuchsia.googlesource.com/integration/+/206f30adff68d2f203d0c6caf5b34045baf56ea9"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "206f30adff68d2f203d0c6caf5b34045baf56ea9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937465758939856816"
},
{
"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"
}
}
[19:17:55.844] Scheduled build:
{
"id": "8772035697053373233",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-22T19:17:55.329979155Z",
"updateTime": "2023-08-22T19:17:55.329979155Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "206f30adff68d2f203d0c6caf5b34045baf56ea9",
"ref": "refs/heads/main"
}
}
}
[19:17:55.844] Task URL: https://cr-buildbucket.appspot.com/build/8772035697053373233
[19:17:55.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:2:0) after 8m11s
[19:17:56.688] Received PubSub notification, asking Buildbucket for the build status
[19:17:56.723] Build status: SCHEDULED
[19:18:13.169] Received PubSub notification, asking Buildbucket for the build status
[19:18:13.237] Build status: STARTED
[19:26:06.862] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:2:0)
[19:26:06.884] Build status: STARTED
[19:26:06.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:5:0) after 3m32s
[19:29:40.110] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:5:0)
[19:29:40.136] Build status: STARTED
[19:29:40.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:6:0) after 9m48s
[19:39:28.224] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:6:0)
[19:39:28.259] Build status: STARTED
[19:39:28.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:7:0) after 7m3s
[19:46:31.560] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:7:0)
[19:46:31.593] Build status: STARTED
[19:46:31.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:8:0) after 9m48s
[19:56:19.896] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:8:0)
[19:56:19.915] Build status: STARTED
[19:56:19.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:9:0) after 8m10s
[20:04:29.932] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:9:0)
[20:04:29.952] Build status: STARTED
[20:04:29.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:10:0) after 9m14s
[20:13:43.973] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:10:0)
[20:13:43.993] Build status: STARTED
[20:13:43.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:11:0) after 5m4s
[20:18:48.009] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:11:0)
[20:18:48.030] Build status: STARTED
[20:18:48.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:12:0) after 7m27s
[20:26:15.051] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:12:0)
[20:26:15.094] Build status: STARTED
[20:26:15.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:13:0) after 2m45s
[20:29:00.113] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:13:0)
[20:29:00.134] Build status: STARTED
[20:29:00.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:14:0) after 8m25s
[20:37:25.178] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:14:0)
[20:37:25.240] Build status: STARTED
[20:37:25.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937465758939856816:15:0) after 6m16s
[20:43:05.842] Received PubSub notification, asking Buildbucket for the build status
[20:43:05.860] Build:
{
"id": "8772035697053373233",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-22T19:17:55.329979155Z",
"startTime": "2023-08-22T19:18:12.469393Z",
"endTime": "2023-08-22T20:43:05.191191206Z",
"updateTime": "2023-08-22T20:43:05.191191206Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "206f30adff68d2f203d0c6caf5b34045baf56ea9",
"ref": "refs/heads/main"
}
}
}
[20:43:05.860] Invocation finished in 1h25m11.713740771s with status SUCCEEDED