[22:40:08.176] New invocation is queued and will start shortly
[22:40:09.297] Starting the invocation (attempt 1)
[22:40:09.327] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/34d23d0eea6de0559b2eeffc4a06fb6e863c719e"
[22:40:09.327] Popped gitiles commit info from properties and tags
[22:40:09.327] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:09.327] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:09.327] Buildbucket request:
{
"requestId": "8939989750553773504",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939989750553773504",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6519eaff6d784e9131f4349b183bbbce8b73082e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6519eaff6d784e9131f4349b183bbbce8b73082e",
"title": "6519eaff6d784e9131f4349b183bbbce8b73082e",
"url": "https://fuchsia.googlesource.com/integration/+/6519eaff6d784e9131f4349b183bbbce8b73082e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2ddbfc4094eecbb48f4baa9ce4618ac57cd1341b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2ddbfc4094eecbb48f4baa9ce4618ac57cd1341b",
"title": "2ddbfc4094eecbb48f4baa9ce4618ac57cd1341b",
"url": "https://fuchsia.googlesource.com/integration/+/2ddbfc4094eecbb48f4baa9ce4618ac57cd1341b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5e530677dd3c6e89eefeb9f65226e16e70affd8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5e530677dd3c6e89eefeb9f65226e16e70affd8",
"title": "b5e530677dd3c6e89eefeb9f65226e16e70affd8",
"url": "https://fuchsia.googlesource.com/integration/+/b5e530677dd3c6e89eefeb9f65226e16e70affd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2255c99db7239b7688aad359280fba22a6fa8126"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2255c99db7239b7688aad359280fba22a6fa8126",
"title": "2255c99db7239b7688aad359280fba22a6fa8126",
"url": "https://fuchsia.googlesource.com/integration/+/2255c99db7239b7688aad359280fba22a6fa8126"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8809b8016e0523255d35b6bf3086efb568ca3805"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8809b8016e0523255d35b6bf3086efb568ca3805",
"title": "8809b8016e0523255d35b6bf3086efb568ca3805",
"url": "https://fuchsia.googlesource.com/integration/+/8809b8016e0523255d35b6bf3086efb568ca3805"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "001bf0d830727e5050fb3ad5c4b49288fa63c05b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@001bf0d830727e5050fb3ad5c4b49288fa63c05b",
"title": "001bf0d830727e5050fb3ad5c4b49288fa63c05b",
"url": "https://fuchsia.googlesource.com/integration/+/001bf0d830727e5050fb3ad5c4b49288fa63c05b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7cb36fe23855560551d01b7f8ec81bb55079bc58"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7cb36fe23855560551d01b7f8ec81bb55079bc58",
"title": "7cb36fe23855560551d01b7f8ec81bb55079bc58",
"url": "https://fuchsia.googlesource.com/integration/+/7cb36fe23855560551d01b7f8ec81bb55079bc58"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "99445543ea2e7141608a0439b57a3b8d9d5c819e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@99445543ea2e7141608a0439b57a3b8d9d5c819e",
"title": "99445543ea2e7141608a0439b57a3b8d9d5c819e",
"url": "https://fuchsia.googlesource.com/integration/+/99445543ea2e7141608a0439b57a3b8d9d5c819e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "718ea7d4169822ff69dfc2aaaa492d9b6c405782"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@718ea7d4169822ff69dfc2aaaa492d9b6c405782",
"title": "718ea7d4169822ff69dfc2aaaa492d9b6c405782",
"url": "https://fuchsia.googlesource.com/integration/+/718ea7d4169822ff69dfc2aaaa492d9b6c405782"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "77cb424c45b3e20c66aa48715b2ff4fade75279f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@77cb424c45b3e20c66aa48715b2ff4fade75279f",
"title": "77cb424c45b3e20c66aa48715b2ff4fade75279f",
"url": "https://fuchsia.googlesource.com/integration/+/77cb424c45b3e20c66aa48715b2ff4fade75279f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2f592f37b4c26014345aa6fbdb18aea691e0e28e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2f592f37b4c26014345aa6fbdb18aea691e0e28e",
"title": "2f592f37b4c26014345aa6fbdb18aea691e0e28e",
"url": "https://fuchsia.googlesource.com/integration/+/2f592f37b4c26014345aa6fbdb18aea691e0e28e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dbbdb518e0ff3f6db32bf811aed97932b511aa4b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dbbdb518e0ff3f6db32bf811aed97932b511aa4b",
"title": "dbbdb518e0ff3f6db32bf811aed97932b511aa4b",
"url": "https://fuchsia.googlesource.com/integration/+/dbbdb518e0ff3f6db32bf811aed97932b511aa4b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1246861a4937b64ab25e8a954eca8c82f7874644"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1246861a4937b64ab25e8a954eca8c82f7874644",
"title": "1246861a4937b64ab25e8a954eca8c82f7874644",
"url": "https://fuchsia.googlesource.com/integration/+/1246861a4937b64ab25e8a954eca8c82f7874644"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b0bc9b2a3b4a6de9243d8b761635a7f934711427"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b0bc9b2a3b4a6de9243d8b761635a7f934711427",
"title": "b0bc9b2a3b4a6de9243d8b761635a7f934711427",
"url": "https://fuchsia.googlesource.com/integration/+/b0bc9b2a3b4a6de9243d8b761635a7f934711427"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "744564d00ac51e49a13c8f7a4ee16c158dafd3f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@744564d00ac51e49a13c8f7a4ee16c158dafd3f0",
"title": "744564d00ac51e49a13c8f7a4ee16c158dafd3f0",
"url": "https://fuchsia.googlesource.com/integration/+/744564d00ac51e49a13c8f7a4ee16c158dafd3f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a422f30cf497ff1fe7bf2b7bdca80b56e3ae335b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a422f30cf497ff1fe7bf2b7bdca80b56e3ae335b",
"title": "a422f30cf497ff1fe7bf2b7bdca80b56e3ae335b",
"url": "https://fuchsia.googlesource.com/integration/+/a422f30cf497ff1fe7bf2b7bdca80b56e3ae335b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ce8cdc92077101862d6084fddbd70fe94974490"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ce8cdc92077101862d6084fddbd70fe94974490",
"title": "6ce8cdc92077101862d6084fddbd70fe94974490",
"url": "https://fuchsia.googlesource.com/integration/+/6ce8cdc92077101862d6084fddbd70fe94974490"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dc5bd2ee4a2697122ca004af3aaff129566e9fe8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dc5bd2ee4a2697122ca004af3aaff129566e9fe8",
"title": "dc5bd2ee4a2697122ca004af3aaff129566e9fe8",
"url": "https://fuchsia.googlesource.com/integration/+/dc5bd2ee4a2697122ca004af3aaff129566e9fe8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "681859df66dc035a18e07b600a21cdd440a168cf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@681859df66dc035a18e07b600a21cdd440a168cf",
"title": "681859df66dc035a18e07b600a21cdd440a168cf",
"url": "https://fuchsia.googlesource.com/integration/+/681859df66dc035a18e07b600a21cdd440a168cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "34d23d0eea6de0559b2eeffc4a06fb6e863c719e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@34d23d0eea6de0559b2eeffc4a06fb6e863c719e",
"title": "34d23d0eea6de0559b2eeffc4a06fb6e863c719e",
"url": "https://fuchsia.googlesource.com/integration/+/34d23d0eea6de0559b2eeffc4a06fb6e863c719e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "34d23d0eea6de0559b2eeffc4a06fb6e863c719e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939989750553773504"
},
{
"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"
}
}
[22:40:09.779] Scheduled build:
{
"id": "8774559688624187745",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-25T22:40:09.393890929Z",
"updateTime": "2023-07-25T22:40:09.393890929Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "34d23d0eea6de0559b2eeffc4a06fb6e863c719e",
"ref": "refs/heads/main"
}
}
}
[22:40:09.779] Task URL: https://cr-buildbucket.appspot.com/build/8774559688624187745
[22:40:09.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:2:0) after 2m55s
[22:40:10.858] Received PubSub notification, asking Buildbucket for the build status
[22:40:10.893] Build status: SCHEDULED
[22:40:12.370] Received PubSub notification, asking Buildbucket for the build status
[22:40:12.441] Build status: STARTED
[22:43:04.794] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:2:0)
[22:43:04.827] Build status: STARTED
[22:43:04.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:5:0) after 2m32s
[22:45:36.830] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:5:0)
[22:45:36.853] Build status: STARTED
[22:45:36.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:6:0) after 8m11s
[22:53:47.891] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:6:0)
[22:53:47.920] Build status: STARTED
[22:53:47.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:7:0) after 3m9s
[22:56:57.021] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:7:0)
[22:56:57.052] Build status: STARTED
[22:56:57.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:8:0) after 7m48s
[23:04:45.176] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:8:0)
[23:04:45.204] Build status: STARTED
[23:04:45.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:9:0) after 7m6s
[23:11:51.230] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:9:0)
[23:11:51.248] Build status: STARTED
[23:11:51.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:10:0) after 5m48s
[23:17:39.519] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:10:0)
[23:17:39.539] Build status: STARTED
[23:17:39.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:11:0) after 2m42s
[23:20:21.565] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:11:0)
[23:20:21.588] Build status: STARTED
[23:20:21.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:12:0) after 4m20s
[23:24:41.611] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:12:0)
[23:24:41.630] Build status: STARTED
[23:24:41.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:13:0) after 7m11s
[23:31:52.748] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:13:0)
[23:31:52.768] Build status: STARTED
[23:31:52.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:14:0) after 2m40s
[23:34:32.784] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:14:0)
[23:34:32.804] Build status: STARTED
[23:34:32.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:15:0) after 2m11s
[23:36:44.030] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:15:0)
[23:36:44.047] Build status: STARTED
[23:36:44.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:16:0) after 2m45s
[23:39:29.052] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:16:0)
[23:39:29.132] Build status: STARTED
[23:39:29.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:17:0) after 1m23s
[23:40:52.300] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:17:0)
[23:40:52.335] Build status: STARTED
[23:40:52.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:18:0) after 3m59s
[23:44:51.513] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:18:0)
[23:44:51.541] Build status: STARTED
[23:44:51.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:19:0) after 7m52s
[23:52:43.635] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:19:0)
[23:52:43.654] Build status: STARTED
[23:52:43.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:20:0) after 6m32s
[23:59:15.694] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:20:0)
[23:59:15.709] Build status: STARTED
[23:59:15.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:21:0) after 3m25s
[00:02:40.916] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:21:0)
[00:02:40.937] Build status: STARTED
[00:02:40.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8939989750553773504:22:0) after 6m47s
[00:02:55.766] Received PubSub notification, asking Buildbucket for the build status
[00:02:55.786] Build:
{
"id": "8774559688624187745",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-25T22:40:09.393890929Z",
"startTime": "2023-07-25T22:40:11.333984Z",
"endTime": "2023-07-26T00:02:54.675574001Z",
"updateTime": "2023-07-26T00:02:54.675574001Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "34d23d0eea6de0559b2eeffc4a06fb6e863c719e",
"ref": "refs/heads/main"
}
}
}
[00:02:55.786] Invocation finished in 1h22m47.617553828s with status SUCCEEDED