[19:05:20.927] New invocation is queued and will start shortly
[19:05:22.402] Starting the invocation (attempt 1)
[19:05:22.443] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2950f22d097137fe7d66b67f6eaa216565e9753e"
[19:05:22.443] Popped gitiles commit info from properties and tags
[19:05:22.443] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:05:22.443] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:05:22.444] Buildbucket request:
{
"requestId": "8928406852115784384",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928406852115784384",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3bf9207633d838f6e5d630983401c2ae890e2e32"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3bf9207633d838f6e5d630983401c2ae890e2e32",
"title": "3bf9207633d838f6e5d630983401c2ae890e2e32",
"url": "https://fuchsia.googlesource.com/integration/+/3bf9207633d838f6e5d630983401c2ae890e2e32"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "509049547469d5e7799a3e1b8a8082798de9210e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@509049547469d5e7799a3e1b8a8082798de9210e",
"title": "509049547469d5e7799a3e1b8a8082798de9210e",
"url": "https://fuchsia.googlesource.com/integration/+/509049547469d5e7799a3e1b8a8082798de9210e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5b0fa1b54d6af304687ca35fffbd5e81d43faf1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5b0fa1b54d6af304687ca35fffbd5e81d43faf1",
"title": "b5b0fa1b54d6af304687ca35fffbd5e81d43faf1",
"url": "https://fuchsia.googlesource.com/integration/+/b5b0fa1b54d6af304687ca35fffbd5e81d43faf1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c429ca816ea21b4062075efe171a1d6ac8e42112"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c429ca816ea21b4062075efe171a1d6ac8e42112",
"title": "c429ca816ea21b4062075efe171a1d6ac8e42112",
"url": "https://fuchsia.googlesource.com/integration/+/c429ca816ea21b4062075efe171a1d6ac8e42112"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "60e9da4cb981faa5fc9bba21ba15f58f93667ca3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@60e9da4cb981faa5fc9bba21ba15f58f93667ca3",
"title": "60e9da4cb981faa5fc9bba21ba15f58f93667ca3",
"url": "https://fuchsia.googlesource.com/integration/+/60e9da4cb981faa5fc9bba21ba15f58f93667ca3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8603e8748f83d7d4e903baa31702b4f7653c4649"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8603e8748f83d7d4e903baa31702b4f7653c4649",
"title": "8603e8748f83d7d4e903baa31702b4f7653c4649",
"url": "https://fuchsia.googlesource.com/integration/+/8603e8748f83d7d4e903baa31702b4f7653c4649"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2cdfbc22b60c06da61c9c4d78cbcc71d604f932a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2cdfbc22b60c06da61c9c4d78cbcc71d604f932a",
"title": "2cdfbc22b60c06da61c9c4d78cbcc71d604f932a",
"url": "https://fuchsia.googlesource.com/integration/+/2cdfbc22b60c06da61c9c4d78cbcc71d604f932a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ef054ae65a8cef50e121337ce92ab72776c46daf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ef054ae65a8cef50e121337ce92ab72776c46daf",
"title": "ef054ae65a8cef50e121337ce92ab72776c46daf",
"url": "https://fuchsia.googlesource.com/integration/+/ef054ae65a8cef50e121337ce92ab72776c46daf"
},
{
"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"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2950f22d097137fe7d66b67f6eaa216565e9753e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928406852115784384"
},
{
"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:05:22.931] Scheduled build:
{
"id": "8762976789789286593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-30T19:05:22.521960501Z",
"updateTime": "2023-11-30T19:05:22.521960501Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2950f22d097137fe7d66b67f6eaa216565e9753e",
"ref": "refs/heads/main"
}
}
}
[19:05:22.931] Task URL: https://cr-buildbucket.appspot.com/build/8762976789789286593
[19:05:22.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:2:0) after 9m16s
[19:05:23.885] Received PubSub notification, asking Buildbucket for the build status
[19:05:23.903] Build status: SCHEDULED
[19:05:37.815] Received PubSub notification, asking Buildbucket for the build status
[19:05:37.845] Build status: STARTED
[19:14:38.979] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:2:0)
[19:14:38.998] Build status: STARTED
[19:14:38.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:5:0) after 4m6s
[19:18:45.066] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:5:0)
[19:18:45.088] Build status: STARTED
[19:18:45.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:6:0) after 3m35s
[19:22:20.790] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:6:0)
[19:22:20.885] Build status: STARTED
[19:22:20.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:7:0) after 7m11s
[19:29:31.911] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:7:0)
[19:29:31.933] Build status: STARTED
[19:29:31.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:8:0) after 3m43s
[19:33:14.939] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:8:0)
[19:33:14.957] Build status: STARTED
[19:33:14.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:9:0) after 9m4s
[19:42:18.997] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:9:0)
[19:42:19.016] Build status: STARTED
[19:42:19.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:10:0) after 3m59s
[19:46:18.291] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:10:0)
[19:46:18.311] Build status: STARTED
[19:46:18.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:11:0) after 2m38s
[19:48:56.335] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:11:0)
[19:48:56.489] Build status: STARTED
[19:48:56.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:12:0) after 5m31s
[19:54:27.473] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:12:0)
[19:54:27.489] Build status: STARTED
[19:54:27.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:13:0) after 2m29s
[19:56:56.457] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:13:0)
[19:56:56.478] Build status: STARTED
[19:56:56.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:14:0) after 5m11s
[20:02:07.547] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:14:0)
[20:02:07.579] Build status: STARTED
[20:02:07.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:15:0) after 7m18s
[20:09:25.599] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:15:0)
[20:09:25.622] Build status: STARTED
[20:09:25.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:16:0) after 3m39s
[20:13:04.643] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:16:0)
[20:13:04.675] Build status: STARTED
[20:13:04.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:17:0) after 2m41s
[20:15:45.693] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:17:0)
[20:15:45.761] Build status: STARTED
[20:15:45.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:18:0) after 5m5s
[20:20:50.887] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:18:0)
[20:20:50.909] Build status: STARTED
[20:20:50.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:19:0) after 8m52s
[20:29:42.938] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:19:0)
[20:29:42.967] Build status: STARTED
[20:29:42.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:20:0) after 7m17s
[20:36:59.985] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:20:0)
[20:37:00.004] Build status: STARTED
[20:37:00.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:21:0) after 5m54s
[20:42:54.048] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:21:0)
[20:42:54.075] Build status: STARTED
[20:42:54.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:22:0) after 9m16s
[20:52:10.193] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:22:0)
[20:52:10.216] Build status: STARTED
[20:52:10.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:23:0) after 1m31s
[20:53:41.291] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:23:0)
[20:53:41.311] Build status: STARTED
[20:53:41.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:24:0) after 6m57s
[21:00:38.388] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:24:0)
[21:00:38.444] Build status: STARTED
[21:00:38.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928406852115784384:25:0) after 9m29s
[21:05:27.073] Received PubSub notification, asking Buildbucket for the build status
[21:05:27.098] Build:
{
"id": "8762976789789286593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-30T19:05:22.521960501Z",
"startTime": "2023-11-30T19:05:37.223688Z",
"endTime": "2023-11-30T21:05:26.103281Z",
"updateTime": "2023-11-30T21:05:26.103281Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2950f22d097137fe7d66b67f6eaa216565e9753e",
"ref": "refs/heads/main"
}
}
}
[21:05:27.098] Invocation finished in 2h0m6.178467988s with status SUCCEEDED