[19:11:59.347] New invocation is queued and will start shortly
[19:12:00.427] Starting the invocation (attempt 1)
[19:12:00.455] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/db0a49003670d9c2a16a2de658c0bffce0390997"
[19:12:00.455] Popped gitiles commit info from properties and tags
[19:12:00.455] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:12:00.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:12:00.456] Buildbucket request:
{
"requestId": "8932211506933693008",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932211506933693008",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30f2f409e90be895c6a5bf394c37ffdfa248af40"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30f2f409e90be895c6a5bf394c37ffdfa248af40",
"title": "30f2f409e90be895c6a5bf394c37ffdfa248af40",
"url": "https://fuchsia.googlesource.com/integration/+/30f2f409e90be895c6a5bf394c37ffdfa248af40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d4b878ed004858420464dc27cff8e7ba078fdb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d4b878ed004858420464dc27cff8e7ba078fdb0",
"title": "8d4b878ed004858420464dc27cff8e7ba078fdb0",
"url": "https://fuchsia.googlesource.com/integration/+/8d4b878ed004858420464dc27cff8e7ba078fdb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "75dadc76847472f60d7ffad91a157f74cca8e1ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@75dadc76847472f60d7ffad91a157f74cca8e1ce",
"title": "75dadc76847472f60d7ffad91a157f74cca8e1ce",
"url": "https://fuchsia.googlesource.com/integration/+/75dadc76847472f60d7ffad91a157f74cca8e1ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d35ae2995ef110fb18167482f9c9c25004f34322"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d35ae2995ef110fb18167482f9c9c25004f34322",
"title": "d35ae2995ef110fb18167482f9c9c25004f34322",
"url": "https://fuchsia.googlesource.com/integration/+/d35ae2995ef110fb18167482f9c9c25004f34322"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18aaece4cdafad74b820e976cd100c4326ba44ef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18aaece4cdafad74b820e976cd100c4326ba44ef",
"title": "18aaece4cdafad74b820e976cd100c4326ba44ef",
"url": "https://fuchsia.googlesource.com/integration/+/18aaece4cdafad74b820e976cd100c4326ba44ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "310a811cfe98c2853fa4ffbe6643401363612948"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@310a811cfe98c2853fa4ffbe6643401363612948",
"title": "310a811cfe98c2853fa4ffbe6643401363612948",
"url": "https://fuchsia.googlesource.com/integration/+/310a811cfe98c2853fa4ffbe6643401363612948"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c65cfe0881f2606dce51068ec448b25dd14e5751"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c65cfe0881f2606dce51068ec448b25dd14e5751",
"title": "c65cfe0881f2606dce51068ec448b25dd14e5751",
"url": "https://fuchsia.googlesource.com/integration/+/c65cfe0881f2606dce51068ec448b25dd14e5751"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f4e5b8152721e403fb6b30bce1a203586c5daf5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f4e5b8152721e403fb6b30bce1a203586c5daf5",
"title": "4f4e5b8152721e403fb6b30bce1a203586c5daf5",
"url": "https://fuchsia.googlesource.com/integration/+/4f4e5b8152721e403fb6b30bce1a203586c5daf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a66213e252dc648bcb2060b99a806ebf3a9cc45b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a66213e252dc648bcb2060b99a806ebf3a9cc45b",
"title": "a66213e252dc648bcb2060b99a806ebf3a9cc45b",
"url": "https://fuchsia.googlesource.com/integration/+/a66213e252dc648bcb2060b99a806ebf3a9cc45b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d",
"title": "01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d",
"url": "https://fuchsia.googlesource.com/integration/+/01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c16918c5a71a0c10d0b62c65c87dcd8f77cffacd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c16918c5a71a0c10d0b62c65c87dcd8f77cffacd",
"title": "c16918c5a71a0c10d0b62c65c87dcd8f77cffacd",
"url": "https://fuchsia.googlesource.com/integration/+/c16918c5a71a0c10d0b62c65c87dcd8f77cffacd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "559c0abb07f6436ab9efa942a095796dbb803535"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@559c0abb07f6436ab9efa942a095796dbb803535",
"title": "559c0abb07f6436ab9efa942a095796dbb803535",
"url": "https://fuchsia.googlesource.com/integration/+/559c0abb07f6436ab9efa942a095796dbb803535"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7993d8f64d4c65c87fe2c38130a61aca7cc8f5b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7993d8f64d4c65c87fe2c38130a61aca7cc8f5b2",
"title": "7993d8f64d4c65c87fe2c38130a61aca7cc8f5b2",
"url": "https://fuchsia.googlesource.com/integration/+/7993d8f64d4c65c87fe2c38130a61aca7cc8f5b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2447c175f021a96996d6a54b10f827fc5323d628"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2447c175f021a96996d6a54b10f827fc5323d628",
"title": "2447c175f021a96996d6a54b10f827fc5323d628",
"url": "https://fuchsia.googlesource.com/integration/+/2447c175f021a96996d6a54b10f827fc5323d628"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "db0a49003670d9c2a16a2de658c0bffce0390997"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@db0a49003670d9c2a16a2de658c0bffce0390997",
"title": "db0a49003670d9c2a16a2de658c0bffce0390997",
"url": "https://fuchsia.googlesource.com/integration/+/db0a49003670d9c2a16a2de658c0bffce0390997"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "db0a49003670d9c2a16a2de658c0bffce0390997",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932211506933693008"
},
{
"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:12:00.982] Scheduled build:
{
"id": "8766781445051957249",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T19:12:00.515854572Z",
"updateTime": "2023-10-19T19:12:00.515854572Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "db0a49003670d9c2a16a2de658c0bffce0390997",
"ref": "refs/heads/main"
}
}
}
[19:12:00.982] Task URL: https://cr-buildbucket.appspot.com/build/8766781445051957249
[19:12:00.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:2:0) after 4m13s
[19:12:01.892] Received PubSub notification, asking Buildbucket for the build status
[19:12:01.926] Build status: SCHEDULED
[19:12:37.067] Received PubSub notification, asking Buildbucket for the build status
[19:12:37.107] Build status: STARTED
[19:16:14.005] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:2:0)
[19:16:14.029] Build status: STARTED
[19:16:14.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:5:0) after 9m29s
[19:25:43.633] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:5:0)
[19:25:43.657] Build status: STARTED
[19:25:43.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:6:0) after 3m47s
[19:29:30.738] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:6:0)
[19:29:30.770] Build status: STARTED
[19:29:30.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:7:0) after 1m32s
[19:31:02.824] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:7:0)
[19:31:02.846] Build status: STARTED
[19:31:02.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:8:0) after 3m31s
[19:34:33.964] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:8:0)
[19:34:34.002] Build status: STARTED
[19:34:34.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:9:0) after 3m58s
[19:38:32.115] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:9:0)
[19:38:32.145] Build status: STARTED
[19:38:32.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:10:0) after 3m5s
[19:41:37.167] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:10:0)
[19:41:37.193] Build status: STARTED
[19:41:37.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:11:0) after 5m26s
[19:47:03.450] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:11:0)
[19:47:03.536] Build status: STARTED
[19:47:03.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:12:0) after 8m21s
[19:55:24.570] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:12:0)
[19:55:24.602] Build status: STARTED
[19:55:24.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:13:0) after 3m33s
[19:58:57.969] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:13:0)
[19:58:57.986] Build status: STARTED
[19:58:57.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:14:0) after 5m3s
[20:04:02.222] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:14:0)
[20:04:02.265] Build status: STARTED
[20:04:02.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:15:0) after 7m12s
[20:11:14.287] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:15:0)
[20:11:14.309] Build status: STARTED
[20:11:14.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:16:0) after 8m6s
[20:19:20.551] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:16:0)
[20:19:20.575] Build status: STARTED
[20:19:20.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8932211506933693008:17:0) after 8m38s
[20:21:06.738] Received PubSub notification, asking Buildbucket for the build status
[20:21:06.772] Build:
{
"id": "8766781445051957249",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T19:12:00.515854572Z",
"startTime": "2023-10-19T19:12:36.326048Z",
"endTime": "2023-10-19T20:21:06.024502295Z",
"updateTime": "2023-10-19T20:21:06.024502295Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "db0a49003670d9c2a16a2de658c0bffce0390997",
"ref": "refs/heads/main"
}
}
}
[20:21:06.772] Invocation finished in 1h9m7.436291005s with status SUCCEEDED