[23:06:54.600] New invocation is queued and will start shortly
[23:06:56.195] Starting the invocation (attempt 1)
[23:06:56.224] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a"
[23:06:56.224] Popped gitiles commit info from properties and tags
[23:06:56.224] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:06:56.224] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:06:56.225] Buildbucket request:
{
"requestId": "8931018966422147408",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931018966422147408",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "139728c52582eedd5690b8e7b101fdec76ac1e98"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@139728c52582eedd5690b8e7b101fdec76ac1e98",
"title": "139728c52582eedd5690b8e7b101fdec76ac1e98",
"url": "https://fuchsia.googlesource.com/integration/+/139728c52582eedd5690b8e7b101fdec76ac1e98"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83e1d86c9727729a2d50162d4cc6102ed97ab4aa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83e1d86c9727729a2d50162d4cc6102ed97ab4aa",
"title": "83e1d86c9727729a2d50162d4cc6102ed97ab4aa",
"url": "https://fuchsia.googlesource.com/integration/+/83e1d86c9727729a2d50162d4cc6102ed97ab4aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4786c50667315fda3a5935c41d9b95b31ba7298c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4786c50667315fda3a5935c41d9b95b31ba7298c",
"title": "4786c50667315fda3a5935c41d9b95b31ba7298c",
"url": "https://fuchsia.googlesource.com/integration/+/4786c50667315fda3a5935c41d9b95b31ba7298c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1fa202dcc3620cd889c8b32858d2391540389b2c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1fa202dcc3620cd889c8b32858d2391540389b2c",
"title": "1fa202dcc3620cd889c8b32858d2391540389b2c",
"url": "https://fuchsia.googlesource.com/integration/+/1fa202dcc3620cd889c8b32858d2391540389b2c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3b3810e092a5bd95482a6ef7342f0d694718b7f2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3b3810e092a5bd95482a6ef7342f0d694718b7f2",
"title": "3b3810e092a5bd95482a6ef7342f0d694718b7f2",
"url": "https://fuchsia.googlesource.com/integration/+/3b3810e092a5bd95482a6ef7342f0d694718b7f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "387116f06d74eca5ee09156fcd3ac2796cc2cde9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@387116f06d74eca5ee09156fcd3ac2796cc2cde9",
"title": "387116f06d74eca5ee09156fcd3ac2796cc2cde9",
"url": "https://fuchsia.googlesource.com/integration/+/387116f06d74eca5ee09156fcd3ac2796cc2cde9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "912f97c60179f7fffcb01cf69ff9258a53bd8f60"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@912f97c60179f7fffcb01cf69ff9258a53bd8f60",
"title": "912f97c60179f7fffcb01cf69ff9258a53bd8f60",
"url": "https://fuchsia.googlesource.com/integration/+/912f97c60179f7fffcb01cf69ff9258a53bd8f60"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8181403820c6ceea7ca873ca4b1e153224f2bb85"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8181403820c6ceea7ca873ca4b1e153224f2bb85",
"title": "8181403820c6ceea7ca873ca4b1e153224f2bb85",
"url": "https://fuchsia.googlesource.com/integration/+/8181403820c6ceea7ca873ca4b1e153224f2bb85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e730a46581d4a46f50351c07f48e466ac2c21c93"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e730a46581d4a46f50351c07f48e466ac2c21c93",
"title": "e730a46581d4a46f50351c07f48e466ac2c21c93",
"url": "https://fuchsia.googlesource.com/integration/+/e730a46581d4a46f50351c07f48e466ac2c21c93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bd7328a4a181d96b8092a1806115806d84b645ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bd7328a4a181d96b8092a1806115806d84b645ae",
"title": "bd7328a4a181d96b8092a1806115806d84b645ae",
"url": "https://fuchsia.googlesource.com/integration/+/bd7328a4a181d96b8092a1806115806d84b645ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "302630f767c452bc67539c555e80703d278d467d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@302630f767c452bc67539c555e80703d278d467d",
"title": "302630f767c452bc67539c555e80703d278d467d",
"url": "https://fuchsia.googlesource.com/integration/+/302630f767c452bc67539c555e80703d278d467d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3963d9a37a8146041a43f6db62d23690ca639121"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3963d9a37a8146041a43f6db62d23690ca639121",
"title": "3963d9a37a8146041a43f6db62d23690ca639121",
"url": "https://fuchsia.googlesource.com/integration/+/3963d9a37a8146041a43f6db62d23690ca639121"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "feb88cd995e70a22846c9d97d38f3de068719dbd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@feb88cd995e70a22846c9d97d38f3de068719dbd",
"title": "feb88cd995e70a22846c9d97d38f3de068719dbd",
"url": "https://fuchsia.googlesource.com/integration/+/feb88cd995e70a22846c9d97d38f3de068719dbd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1c21fecb5b3d68505a2d79a8f30d221cc1da8486"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1c21fecb5b3d68505a2d79a8f30d221cc1da8486",
"title": "1c21fecb5b3d68505a2d79a8f30d221cc1da8486",
"url": "https://fuchsia.googlesource.com/integration/+/1c21fecb5b3d68505a2d79a8f30d221cc1da8486"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "82853445ff00e74b27a48683a551951c662872ee"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@82853445ff00e74b27a48683a551951c662872ee",
"title": "82853445ff00e74b27a48683a551951c662872ee",
"url": "https://fuchsia.googlesource.com/integration/+/82853445ff00e74b27a48683a551951c662872ee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a",
"title": "6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a",
"url": "https://fuchsia.googlesource.com/integration/+/6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931018966422147408"
},
{
"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"
}
}
[23:06:56.699] Scheduled build:
{
"id": "8765588903987389793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-01T23:06:56.299081282Z",
"updateTime": "2023-11-01T23:06:56.299081282Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a",
"ref": "refs/heads/main"
}
}
}
[23:06:56.699] Task URL: https://cr-buildbucket.appspot.com/build/8765588903987389793
[23:06:56.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:2:0) after 4m19s
[23:06:57.662] Received PubSub notification, asking Buildbucket for the build status
[23:06:57.729] Build status: SCHEDULED
[23:07:39.354] Received PubSub notification, asking Buildbucket for the build status
[23:07:39.372] Build status: STARTED
[23:11:15.697] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:2:0)
[23:11:15.719] Build status: STARTED
[23:11:15.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:5:0) after 6m9s
[23:17:24.784] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:5:0)
[23:17:24.803] Build status: STARTED
[23:17:24.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:6:0) after 7m9s
[23:24:33.877] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:6:0)
[23:24:33.894] Build status: STARTED
[23:24:33.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:7:0) after 9m5s
[23:33:39.009] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:7:0)
[23:33:39.035] Build status: STARTED
[23:33:39.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:8:0) after 7m36s
[23:41:15.601] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:8:0)
[23:41:15.620] Build status: STARTED
[23:41:15.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:9:0) after 4m11s
[23:45:26.840] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:9:0)
[23:45:26.857] Build status: STARTED
[23:45:26.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:10:0) after 6m24s
[23:51:50.874] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:10:0)
[23:51:50.895] Build status: STARTED
[23:51:50.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:11:0) after 2m10s
[23:54:01.557] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:11:0)
[23:54:01.580] Build status: STARTED
[23:54:01.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:12:0) after 9m43s
[00:03:44.862] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:12:0)
[00:03:44.944] Build status: STARTED
[00:03:44.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8931018966422147408:13:0) after 9m56s
[00:12:30.531] Received PubSub notification, asking Buildbucket for the build status
[00:12:30.646] Build:
{
"id": "8765588903987389793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-01T23:06:56.299081282Z",
"startTime": "2023-11-01T23:07:38.774600Z",
"endTime": "2023-11-02T00:12:29.540259Z",
"updateTime": "2023-11-02T00:12:29.540259Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d8bc0b7f56b76f9fb0700e3a56c6e274dade65a",
"ref": "refs/heads/main"
}
}
}
[00:12:30.646] Invocation finished in 1h5m36.051894056s with status SUCCEEDED