[20:10:06.772] New invocation is queued and will start shortly
[20:10:07.846] Starting the invocation (attempt 1)
[20:10:07.876] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/850cdab6f07f5db3840c3f30c54864dde5a5efb0"
[20:10:07.876] Popped gitiles commit info from properties and tags
[20:10:07.876] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:10:07.876] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:10:07.877] Buildbucket request:
{
"requestId": "8928402777512124976",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928402777512124976",
"job": "fuchsia/core.x64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f10571f72935007f6fb444abeb3c5ecf4f66b898"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f10571f72935007f6fb444abeb3c5ecf4f66b898",
"title": "f10571f72935007f6fb444abeb3c5ecf4f66b898",
"url": "https://fuchsia.googlesource.com/integration/+/f10571f72935007f6fb444abeb3c5ecf4f66b898"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09d62bf998ffb243f3dc772622a13e46c70e2305"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09d62bf998ffb243f3dc772622a13e46c70e2305",
"title": "09d62bf998ffb243f3dc772622a13e46c70e2305",
"url": "https://fuchsia.googlesource.com/integration/+/09d62bf998ffb243f3dc772622a13e46c70e2305"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8243d7c12bc0ad6fcf5ea076ac93a9eb17ecac21"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8243d7c12bc0ad6fcf5ea076ac93a9eb17ecac21",
"title": "8243d7c12bc0ad6fcf5ea076ac93a9eb17ecac21",
"url": "https://fuchsia.googlesource.com/integration/+/8243d7c12bc0ad6fcf5ea076ac93a9eb17ecac21"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4d8d688d4eb5dc9acb622757e537c1f52c266fc8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4d8d688d4eb5dc9acb622757e537c1f52c266fc8",
"title": "4d8d688d4eb5dc9acb622757e537c1f52c266fc8",
"url": "https://fuchsia.googlesource.com/integration/+/4d8d688d4eb5dc9acb622757e537c1f52c266fc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30ba2717db3b2a08a4f38fce4ffbd4512033fd41"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30ba2717db3b2a08a4f38fce4ffbd4512033fd41",
"title": "30ba2717db3b2a08a4f38fce4ffbd4512033fd41",
"url": "https://fuchsia.googlesource.com/integration/+/30ba2717db3b2a08a4f38fce4ffbd4512033fd41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30b0178cdeb44d6b1a116f2abfcaec199325ea2d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30b0178cdeb44d6b1a116f2abfcaec199325ea2d",
"title": "30b0178cdeb44d6b1a116f2abfcaec199325ea2d",
"url": "https://fuchsia.googlesource.com/integration/+/30b0178cdeb44d6b1a116f2abfcaec199325ea2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2950f22d097137fe7d66b67f6eaa216565e9753e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2950f22d097137fe7d66b67f6eaa216565e9753e",
"title": "2950f22d097137fe7d66b67f6eaa216565e9753e",
"url": "https://fuchsia.googlesource.com/integration/+/2950f22d097137fe7d66b67f6eaa216565e9753e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f060ce784174746cf0bc363cc14f0104771cf7c8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f060ce784174746cf0bc363cc14f0104771cf7c8",
"title": "f060ce784174746cf0bc363cc14f0104771cf7c8",
"url": "https://fuchsia.googlesource.com/integration/+/f060ce784174746cf0bc363cc14f0104771cf7c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7c35ab50730410c93bd88320dc385074e866148"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7c35ab50730410c93bd88320dc385074e866148",
"title": "e7c35ab50730410c93bd88320dc385074e866148",
"url": "https://fuchsia.googlesource.com/integration/+/e7c35ab50730410c93bd88320dc385074e866148"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4b0cd13154d32399b880ec01d69e189e65cd630e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4b0cd13154d32399b880ec01d69e189e65cd630e",
"title": "4b0cd13154d32399b880ec01d69e189e65cd630e",
"url": "https://fuchsia.googlesource.com/integration/+/4b0cd13154d32399b880ec01d69e189e65cd630e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cedf0d890e87faccfa490c6d1a2b17cd8dbecbe9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cedf0d890e87faccfa490c6d1a2b17cd8dbecbe9",
"title": "cedf0d890e87faccfa490c6d1a2b17cd8dbecbe9",
"url": "https://fuchsia.googlesource.com/integration/+/cedf0d890e87faccfa490c6d1a2b17cd8dbecbe9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "947940cbc4ebc8e7dd842f4bba4b41448439ee2b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@947940cbc4ebc8e7dd842f4bba4b41448439ee2b",
"title": "947940cbc4ebc8e7dd842f4bba4b41448439ee2b",
"url": "https://fuchsia.googlesource.com/integration/+/947940cbc4ebc8e7dd842f4bba4b41448439ee2b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "56cd8ff71865cdc71b9f928a7ae2e71574fc7e83"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@56cd8ff71865cdc71b9f928a7ae2e71574fc7e83",
"title": "56cd8ff71865cdc71b9f928a7ae2e71574fc7e83",
"url": "https://fuchsia.googlesource.com/integration/+/56cd8ff71865cdc71b9f928a7ae2e71574fc7e83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "655739a663920f4b6c3b50bd838530bca2b95dfc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@655739a663920f4b6c3b50bd838530bca2b95dfc",
"title": "655739a663920f4b6c3b50bd838530bca2b95dfc",
"url": "https://fuchsia.googlesource.com/integration/+/655739a663920f4b6c3b50bd838530bca2b95dfc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2919c89832af816b2475b5499e9606b286db671f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2919c89832af816b2475b5499e9606b286db671f",
"title": "2919c89832af816b2475b5499e9606b286db671f",
"url": "https://fuchsia.googlesource.com/integration/+/2919c89832af816b2475b5499e9606b286db671f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f82fd487db32c33f739cbaeba30341a4b9803f4b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f82fd487db32c33f739cbaeba30341a4b9803f4b",
"title": "f82fd487db32c33f739cbaeba30341a4b9803f4b",
"url": "https://fuchsia.googlesource.com/integration/+/f82fd487db32c33f739cbaeba30341a4b9803f4b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "850cdab6f07f5db3840c3f30c54864dde5a5efb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@850cdab6f07f5db3840c3f30c54864dde5a5efb0",
"title": "850cdab6f07f5db3840c3f30c54864dde5a5efb0",
"url": "https://fuchsia.googlesource.com/integration/+/850cdab6f07f5db3840c3f30c54864dde5a5efb0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "850cdab6f07f5db3840c3f30c54864dde5a5efb0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928402777512124976"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:10:08.430] Scheduled build:
{
"id": "8762972715636920593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-30T20:10:07.936727219Z",
"updateTime": "2023-11-30T20:10:07.936727219Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "850cdab6f07f5db3840c3f30c54864dde5a5efb0",
"ref": "refs/heads/main"
}
}
}
[20:10:08.430] Task URL: https://cr-buildbucket.appspot.com/build/8762972715636920593
[20:10:08.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:2:0) after 9m31s
[20:10:08.526] Received PubSub notification, asking Buildbucket for the build status
[20:10:08.558] Build status: SCHEDULED
[20:10:10.307] Received PubSub notification, asking Buildbucket for the build status
[20:10:10.324] Build status: STARTED
[20:19:39.469] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:2:0)
[20:19:39.524] Build status: STARTED
[20:19:39.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:5:0) after 1m7s
[20:20:46.561] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:5:0)
[20:20:46.686] Build status: STARTED
[20:20:46.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:6:0) after 7m32s
[20:28:18.704] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:6:0)
[20:28:18.721] Build status: STARTED
[20:28:18.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:7:0) after 7m48s
[20:36:06.806] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:7:0)
[20:36:06.823] Build status: STARTED
[20:36:06.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:8:0) after 4m29s
[20:40:35.852] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:8:0)
[20:40:35.871] Build status: STARTED
[20:40:35.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:9:0) after 5m10s
[20:45:45.891] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:9:0)
[20:45:46.063] Build status: STARTED
[20:45:46.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:10:0) after 5m57s
[20:51:43.141] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:10:0)
[20:51:43.226] Build status: STARTED
[20:51:43.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:11:0) after 6m13s
[20:57:56.242] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:11:0)
[20:57:56.268] Build status: STARTED
[20:57:56.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:12:0) after 2m3s
[20:59:59.777] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:12:0)
[20:59:59.805] Build status: STARTED
[20:59:59.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:13:0) after 6m7s
[21:06:07.112] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:13:0)
[21:06:07.139] Build status: STARTED
[21:06:07.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:14:0) after 9m46s
[21:15:53.587] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:14:0)
[21:15:53.647] Build status: STARTED
[21:15:53.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:15:0) after 7m19s
[21:23:12.636] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:15:0)
[21:23:12.671] Build status: STARTED
[21:23:12.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8928402777512124976:16:0) after 2m30s
[21:23:26.601] Received PubSub notification, asking Buildbucket for the build status
[21:23:26.632] Build:
{
"id": "8762972715636920593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-30T20:10:07.936727219Z",
"startTime": "2023-11-30T20:10:09.574502Z",
"endTime": "2023-11-30T21:23:24.649937Z",
"updateTime": "2023-11-30T21:23:24.649937Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "850cdab6f07f5db3840c3f30c54864dde5a5efb0",
"ref": "refs/heads/main"
}
}
}
[21:23:26.632] Invocation finished in 1h13m19.867718284s with status SUCCEEDED