[21:02:56.496] New invocation is queued and will start shortly
[21:02:59.457] Starting the invocation (attempt 1)
[21:02:59.485] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/245c9be5d531bbb305cc230b9529e09bf80d57fe"
[21:02:59.485] Popped gitiles commit info from properties and tags
[21:02:59.485] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:02:59.485] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:02:59.485] Buildbucket request:
{
"requestId": "8938636911021163856",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938636911021163856",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a81bb641adecdbafc5a07b13bff02cd58f5c1c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a81bb641adecdbafc5a07b13bff02cd58f5c1c2",
"title": "8a81bb641adecdbafc5a07b13bff02cd58f5c1c2",
"url": "https://fuchsia.googlesource.com/integration/+/8a81bb641adecdbafc5a07b13bff02cd58f5c1c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "087bada3d62e2e2b5b8f4b8e10bd9b88a724b064"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@087bada3d62e2e2b5b8f4b8e10bd9b88a724b064",
"title": "087bada3d62e2e2b5b8f4b8e10bd9b88a724b064",
"url": "https://fuchsia.googlesource.com/integration/+/087bada3d62e2e2b5b8f4b8e10bd9b88a724b064"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "46c78e43495629c60ecbacf81e81b894bb80cc0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@46c78e43495629c60ecbacf81e81b894bb80cc0a",
"title": "46c78e43495629c60ecbacf81e81b894bb80cc0a",
"url": "https://fuchsia.googlesource.com/integration/+/46c78e43495629c60ecbacf81e81b894bb80cc0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5c39fc8acf5d3ec6af141470ff8d946ca27dfd98"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5c39fc8acf5d3ec6af141470ff8d946ca27dfd98",
"title": "5c39fc8acf5d3ec6af141470ff8d946ca27dfd98",
"url": "https://fuchsia.googlesource.com/integration/+/5c39fc8acf5d3ec6af141470ff8d946ca27dfd98"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b15a4ef1b8bf4ff08d4e2445697dc12eb9f6ce0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b15a4ef1b8bf4ff08d4e2445697dc12eb9f6ce0",
"title": "0b15a4ef1b8bf4ff08d4e2445697dc12eb9f6ce0",
"url": "https://fuchsia.googlesource.com/integration/+/0b15a4ef1b8bf4ff08d4e2445697dc12eb9f6ce0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad88a028dab120be85c8b318e441b054b8606b17"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad88a028dab120be85c8b318e441b054b8606b17",
"title": "ad88a028dab120be85c8b318e441b054b8606b17",
"url": "https://fuchsia.googlesource.com/integration/+/ad88a028dab120be85c8b318e441b054b8606b17"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09a6c1802e7bf3df94f88d5b6827aa590d10c7c7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09a6c1802e7bf3df94f88d5b6827aa590d10c7c7",
"title": "09a6c1802e7bf3df94f88d5b6827aa590d10c7c7",
"url": "https://fuchsia.googlesource.com/integration/+/09a6c1802e7bf3df94f88d5b6827aa590d10c7c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e885929acc08c0d9c314c337af855a3bb7b342e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e885929acc08c0d9c314c337af855a3bb7b342e",
"title": "2e885929acc08c0d9c314c337af855a3bb7b342e",
"url": "https://fuchsia.googlesource.com/integration/+/2e885929acc08c0d9c314c337af855a3bb7b342e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c8e7414bcfb1e123f5b4aa145135617fda3a26fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c8e7414bcfb1e123f5b4aa145135617fda3a26fe",
"title": "c8e7414bcfb1e123f5b4aa145135617fda3a26fe",
"url": "https://fuchsia.googlesource.com/integration/+/c8e7414bcfb1e123f5b4aa145135617fda3a26fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "efe81bf0cc435369c1ce661c124c48147f7cd757"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@efe81bf0cc435369c1ce661c124c48147f7cd757",
"title": "efe81bf0cc435369c1ce661c124c48147f7cd757",
"url": "https://fuchsia.googlesource.com/integration/+/efe81bf0cc435369c1ce661c124c48147f7cd757"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7b8572e619e3cdd151c63884f8a5ab2dd18b2662"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7b8572e619e3cdd151c63884f8a5ab2dd18b2662",
"title": "7b8572e619e3cdd151c63884f8a5ab2dd18b2662",
"url": "https://fuchsia.googlesource.com/integration/+/7b8572e619e3cdd151c63884f8a5ab2dd18b2662"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5edb3e1401ef01c13e7f2dad947389e25402786d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5edb3e1401ef01c13e7f2dad947389e25402786d",
"title": "5edb3e1401ef01c13e7f2dad947389e25402786d",
"url": "https://fuchsia.googlesource.com/integration/+/5edb3e1401ef01c13e7f2dad947389e25402786d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "89f3117cb610ab26beb8494dc419a6fc5e3139ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@89f3117cb610ab26beb8494dc419a6fc5e3139ba",
"title": "89f3117cb610ab26beb8494dc419a6fc5e3139ba",
"url": "https://fuchsia.googlesource.com/integration/+/89f3117cb610ab26beb8494dc419a6fc5e3139ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b23916b6967f296d0285010a620aecd7a0367e9b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b23916b6967f296d0285010a620aecd7a0367e9b",
"title": "b23916b6967f296d0285010a620aecd7a0367e9b",
"url": "https://fuchsia.googlesource.com/integration/+/b23916b6967f296d0285010a620aecd7a0367e9b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "245c9be5d531bbb305cc230b9529e09bf80d57fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@245c9be5d531bbb305cc230b9529e09bf80d57fe",
"title": "245c9be5d531bbb305cc230b9529e09bf80d57fe",
"url": "https://fuchsia.googlesource.com/integration/+/245c9be5d531bbb305cc230b9529e09bf80d57fe"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "245c9be5d531bbb305cc230b9529e09bf80d57fe",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938636911021163856"
},
{
"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"
}
}
[21:03:00.027] Scheduled build:
{
"id": "8773206847157979073",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-09T21:02:59.553123299Z",
"updateTime": "2023-08-09T21:02:59.553123299Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "245c9be5d531bbb305cc230b9529e09bf80d57fe",
"ref": "refs/heads/main"
}
}
}
[21:03:00.027] Task URL: https://cr-buildbucket.appspot.com/build/8773206847157979073
[21:03:00.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:2:0) after 4m32s
[21:03:01.093] Received PubSub notification, asking Buildbucket for the build status
[21:03:01.112] Build status: STARTED
[21:03:02.223] Received PubSub notification, asking Buildbucket for the build status
[21:03:02.255] Build status: STARTED
[21:07:32.397] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:2:0)
[21:07:32.433] Build status: STARTED
[21:07:32.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:5:0) after 5m32s
[21:13:04.552] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:5:0)
[21:13:04.573] Build status: STARTED
[21:13:04.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:6:0) after 5m43s
[21:18:47.713] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:6:0)
[21:18:47.749] Build status: STARTED
[21:18:47.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:7:0) after 8m5s
[21:26:52.753] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:7:0)
[21:26:52.775] Build status: STARTED
[21:26:52.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:8:0) after 8m5s
[21:34:57.792] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:8:0)
[21:34:57.814] Build status: STARTED
[21:34:57.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:9:0) after 5m7s
[21:40:04.833] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:9:0)
[21:40:04.853] Build status: STARTED
[21:40:04.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:10:0) after 3m55s
[21:43:59.826] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:10:0)
[21:43:59.864] Build status: STARTED
[21:43:59.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:11:0) after 3m22s
[21:47:21.935] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:11:0)
[21:47:21.955] Build status: STARTED
[21:47:21.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:12:0) after 9m27s
[21:56:49.248] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:12:0)
[21:56:49.269] Build status: STARTED
[21:56:49.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:13:0) after 4m4s
[22:00:53.598] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:13:0)
[22:00:53.614] Build status: STARTED
[22:00:53.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:14:0) after 8m15s
[22:09:08.636] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:14:0)
[22:09:08.661] Build status: STARTED
[22:09:08.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:15:0) after 6m5s
[22:15:13.841] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:15:0)
[22:15:13.862] Build status: STARTED
[22:15:13.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938636911021163856:16:0) after 7m47s
[22:21:52.837] Received PubSub notification, asking Buildbucket for the build status
[22:21:52.865] Build:
{
"id": "8773206847157979073",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-09T21:02:59.553123299Z",
"startTime": "2023-08-09T21:03:00.625070Z",
"endTime": "2023-08-09T22:21:52.054096367Z",
"updateTime": "2023-08-09T22:21:52.054096367Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "245c9be5d531bbb305cc230b9529e09bf80d57fe",
"ref": "refs/heads/main"
}
}
}
[22:21:52.865] Invocation finished in 1h18m56.379697751s with status SUCCEEDED