[00:47:17.359] New invocation is queued and will start shortly
[00:47:18.548] Starting the invocation (attempt 1)
[00:47:18.575] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b4080e21bd755ab3b3d2db14db289f59b7e8dcd1"
[00:47:18.575] Popped gitiles commit info from properties and tags
[00:47:18.575] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:47:18.575] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:47:18.576] Buildbucket request:
{
"requestId": "8935270708534892000",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935270708534892000",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fd59a43b0f72ce5de95dbcda3520a8d6f6ba7019"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fd59a43b0f72ce5de95dbcda3520a8d6f6ba7019",
"title": "fd59a43b0f72ce5de95dbcda3520a8d6f6ba7019",
"url": "https://fuchsia.googlesource.com/integration/+/fd59a43b0f72ce5de95dbcda3520a8d6f6ba7019"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ce8f94ec7fd8d1d643390e5f68b55ae05df3e45f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ce8f94ec7fd8d1d643390e5f68b55ae05df3e45f",
"title": "ce8f94ec7fd8d1d643390e5f68b55ae05df3e45f",
"url": "https://fuchsia.googlesource.com/integration/+/ce8f94ec7fd8d1d643390e5f68b55ae05df3e45f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "78eef73253976d15ce6575cd09bca56406668c08"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@78eef73253976d15ce6575cd09bca56406668c08",
"title": "78eef73253976d15ce6575cd09bca56406668c08",
"url": "https://fuchsia.googlesource.com/integration/+/78eef73253976d15ce6575cd09bca56406668c08"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3a19690d9829551c46c4e7d71e42a7cb61374fcd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3a19690d9829551c46c4e7d71e42a7cb61374fcd",
"title": "3a19690d9829551c46c4e7d71e42a7cb61374fcd",
"url": "https://fuchsia.googlesource.com/integration/+/3a19690d9829551c46c4e7d71e42a7cb61374fcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9b1be60ab7f425d1608c2ef77537c4dcf9505165"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9b1be60ab7f425d1608c2ef77537c4dcf9505165",
"title": "9b1be60ab7f425d1608c2ef77537c4dcf9505165",
"url": "https://fuchsia.googlesource.com/integration/+/9b1be60ab7f425d1608c2ef77537c4dcf9505165"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4663c9e8ac99fbb1b4d0594bebd4dabc261b4f01"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4663c9e8ac99fbb1b4d0594bebd4dabc261b4f01",
"title": "4663c9e8ac99fbb1b4d0594bebd4dabc261b4f01",
"url": "https://fuchsia.googlesource.com/integration/+/4663c9e8ac99fbb1b4d0594bebd4dabc261b4f01"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8b14207631bed39d02e7554188861a03a72ce486"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8b14207631bed39d02e7554188861a03a72ce486",
"title": "8b14207631bed39d02e7554188861a03a72ce486",
"url": "https://fuchsia.googlesource.com/integration/+/8b14207631bed39d02e7554188861a03a72ce486"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e31cc5aab14f8f63a97d235d3f0cb1cab87d4aa5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e31cc5aab14f8f63a97d235d3f0cb1cab87d4aa5",
"title": "e31cc5aab14f8f63a97d235d3f0cb1cab87d4aa5",
"url": "https://fuchsia.googlesource.com/integration/+/e31cc5aab14f8f63a97d235d3f0cb1cab87d4aa5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7d2e5482f60b54b770fbf28ee25afa3904546883"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7d2e5482f60b54b770fbf28ee25afa3904546883",
"title": "7d2e5482f60b54b770fbf28ee25afa3904546883",
"url": "https://fuchsia.googlesource.com/integration/+/7d2e5482f60b54b770fbf28ee25afa3904546883"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9273af43eb1c3167314677e480d0467df4494561"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9273af43eb1c3167314677e480d0467df4494561",
"title": "9273af43eb1c3167314677e480d0467df4494561",
"url": "https://fuchsia.googlesource.com/integration/+/9273af43eb1c3167314677e480d0467df4494561"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "211861dee4e4ff30a6bc69b0449d997d35d561c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@211861dee4e4ff30a6bc69b0449d997d35d561c3",
"title": "211861dee4e4ff30a6bc69b0449d997d35d561c3",
"url": "https://fuchsia.googlesource.com/integration/+/211861dee4e4ff30a6bc69b0449d997d35d561c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b4080e21bd755ab3b3d2db14db289f59b7e8dcd1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b4080e21bd755ab3b3d2db14db289f59b7e8dcd1",
"title": "b4080e21bd755ab3b3d2db14db289f59b7e8dcd1",
"url": "https://fuchsia.googlesource.com/integration/+/b4080e21bd755ab3b3d2db14db289f59b7e8dcd1"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b4080e21bd755ab3b3d2db14db289f59b7e8dcd1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935270708534892000"
},
{
"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"
}
}
[00:47:19.198] Scheduled build:
{
"id": "8769840646532552321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-16T00:47:18.634595345Z",
"updateTime": "2023-09-16T00:47:18.634595345Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b4080e21bd755ab3b3d2db14db289f59b7e8dcd1",
"ref": "refs/heads/main"
}
}
}
[00:47:19.198] Task URL: https://cr-buildbucket.appspot.com/build/8769840646532552321
[00:47:19.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:2:0) after 7m49s
[00:47:20.110] Received PubSub notification, asking Buildbucket for the build status
[00:47:20.128] Build status: SCHEDULED
[00:49:22.677] Received PubSub notification, asking Buildbucket for the build status
[00:49:22.772] Build status: STARTED
[00:55:08.225] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:2:0)
[00:55:08.247] Build status: STARTED
[00:55:08.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:5:0) after 7m8s
[01:02:16.337] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:5:0)
[01:02:16.364] Build status: STARTED
[01:02:16.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:6:0) after 4m40s
[01:06:56.514] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:6:0)
[01:06:56.531] Build status: STARTED
[01:06:56.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:7:0) after 7m27s
[01:14:23.572] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:7:0)
[01:14:23.590] Build status: STARTED
[01:14:23.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:8:0) after 3m0s
[01:17:24.340] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:8:0)
[01:17:24.361] Build status: STARTED
[01:17:24.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:9:0) after 8m5s
[01:25:29.490] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:9:0)
[01:25:29.512] Build status: STARTED
[01:25:29.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:10:0) after 4m40s
[01:30:09.537] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:10:0)
[01:30:09.717] Build status: STARTED
[01:30:09.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:11:0) after 2m16s
[01:32:25.761] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:11:0)
[01:32:25.827] Build status: STARTED
[01:32:25.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:12:0) after 4m51s
[01:37:16.942] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:12:0)
[01:37:16.961] Build status: STARTED
[01:37:16.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:13:0) after 3m44s
[01:41:01.119] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:13:0)
[01:41:01.243] Build status: STARTED
[01:41:01.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:14:0) after 7m44s
[01:48:45.260] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:14:0)
[01:48:45.278] Build status: STARTED
[01:48:45.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:15:0) after 5m20s
[01:54:05.484] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:15:0)
[01:54:05.503] Build status: STARTED
[01:54:05.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935270708534892000:16:0) after 7m36s
[01:55:04.874] Received PubSub notification, asking Buildbucket for the build status
[01:55:04.906] Build:
{
"id": "8769840646532552321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-16T00:47:18.634595345Z",
"startTime": "2023-09-16T00:49:20.891237Z",
"endTime": "2023-09-16T01:55:04.256059337Z",
"updateTime": "2023-09-16T01:55:04.256059337Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b4080e21bd755ab3b3d2db14db289f59b7e8dcd1",
"ref": "refs/heads/main"
}
}
}
[01:55:04.906] Invocation finished in 1h7m47.565610545s with status SUCCEEDED