[23:23:41.276] New invocation is queued and will start shortly
[23:23:42.521] Starting the invocation (attempt 1)
[23:23:42.547] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c9c1138be4d022ea3b33615463a26245ac43f59f"
[23:23:42.547] Popped gitiles commit info from properties and tags
[23:23:42.547] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:23:42.547] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:23:42.547] Buildbucket request:
{
"requestId": "8938537459058490320",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8938537459058490320",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9610ea290ed147423e65a7b6d6f8447ed46d9ffb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9610ea290ed147423e65a7b6d6f8447ed46d9ffb",
"title": "9610ea290ed147423e65a7b6d6f8447ed46d9ffb",
"url": "https://fuchsia.googlesource.com/integration/+/9610ea290ed147423e65a7b6d6f8447ed46d9ffb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d2c1aac945d19fc1ce30733055fd90cdbe61f097"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d2c1aac945d19fc1ce30733055fd90cdbe61f097",
"title": "d2c1aac945d19fc1ce30733055fd90cdbe61f097",
"url": "https://fuchsia.googlesource.com/integration/+/d2c1aac945d19fc1ce30733055fd90cdbe61f097"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb7c05e68c542f2027c6f26b64c7a1b0d82a4a95"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb7c05e68c542f2027c6f26b64c7a1b0d82a4a95",
"title": "eb7c05e68c542f2027c6f26b64c7a1b0d82a4a95",
"url": "https://fuchsia.googlesource.com/integration/+/eb7c05e68c542f2027c6f26b64c7a1b0d82a4a95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e2ef70ba7af79738606fe9f0c0dcb4c4c95c9949"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e2ef70ba7af79738606fe9f0c0dcb4c4c95c9949",
"title": "e2ef70ba7af79738606fe9f0c0dcb4c4c95c9949",
"url": "https://fuchsia.googlesource.com/integration/+/e2ef70ba7af79738606fe9f0c0dcb4c4c95c9949"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "889b9d402ca204c0a56f6e1acb9d61a126c8878f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@889b9d402ca204c0a56f6e1acb9d61a126c8878f",
"title": "889b9d402ca204c0a56f6e1acb9d61a126c8878f",
"url": "https://fuchsia.googlesource.com/integration/+/889b9d402ca204c0a56f6e1acb9d61a126c8878f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15537427d3679dbce39dacf4cd51b550bbd4496c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15537427d3679dbce39dacf4cd51b550bbd4496c",
"title": "15537427d3679dbce39dacf4cd51b550bbd4496c",
"url": "https://fuchsia.googlesource.com/integration/+/15537427d3679dbce39dacf4cd51b550bbd4496c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc81d2da19769ac7568ef4dbfda26f50262e4753"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc81d2da19769ac7568ef4dbfda26f50262e4753",
"title": "bc81d2da19769ac7568ef4dbfda26f50262e4753",
"url": "https://fuchsia.googlesource.com/integration/+/bc81d2da19769ac7568ef4dbfda26f50262e4753"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ada2879af6e3cfdc6daad967233fa3580bfeb437"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ada2879af6e3cfdc6daad967233fa3580bfeb437",
"title": "ada2879af6e3cfdc6daad967233fa3580bfeb437",
"url": "https://fuchsia.googlesource.com/integration/+/ada2879af6e3cfdc6daad967233fa3580bfeb437"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "acde9c5533166b98615d0b46b0adf8344102c291"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@acde9c5533166b98615d0b46b0adf8344102c291",
"title": "acde9c5533166b98615d0b46b0adf8344102c291",
"url": "https://fuchsia.googlesource.com/integration/+/acde9c5533166b98615d0b46b0adf8344102c291"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4af70a4f4d75c971a71671fd8961bb86bd2b5ba7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4af70a4f4d75c971a71671fd8961bb86bd2b5ba7",
"title": "4af70a4f4d75c971a71671fd8961bb86bd2b5ba7",
"url": "https://fuchsia.googlesource.com/integration/+/4af70a4f4d75c971a71671fd8961bb86bd2b5ba7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "068bb38dd77ef33cfddfbc29efaecc778145b77e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@068bb38dd77ef33cfddfbc29efaecc778145b77e",
"title": "068bb38dd77ef33cfddfbc29efaecc778145b77e",
"url": "https://fuchsia.googlesource.com/integration/+/068bb38dd77ef33cfddfbc29efaecc778145b77e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e8aaaca39aca9c1534501f8cbfc5536157a4e84"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e8aaaca39aca9c1534501f8cbfc5536157a4e84",
"title": "4e8aaaca39aca9c1534501f8cbfc5536157a4e84",
"url": "https://fuchsia.googlesource.com/integration/+/4e8aaaca39aca9c1534501f8cbfc5536157a4e84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9619f567ea33eae20395f81b5d4796e60d5b8e83"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9619f567ea33eae20395f81b5d4796e60d5b8e83",
"title": "9619f567ea33eae20395f81b5d4796e60d5b8e83",
"url": "https://fuchsia.googlesource.com/integration/+/9619f567ea33eae20395f81b5d4796e60d5b8e83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "647f2688887c1a2ed607cbd0b2074c82ca2cb095"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@647f2688887c1a2ed607cbd0b2074c82ca2cb095",
"title": "647f2688887c1a2ed607cbd0b2074c82ca2cb095",
"url": "https://fuchsia.googlesource.com/integration/+/647f2688887c1a2ed607cbd0b2074c82ca2cb095"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1fab0df0713cd9b42eaa3d69cc51373dc43c1650"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1fab0df0713cd9b42eaa3d69cc51373dc43c1650",
"title": "1fab0df0713cd9b42eaa3d69cc51373dc43c1650",
"url": "https://fuchsia.googlesource.com/integration/+/1fab0df0713cd9b42eaa3d69cc51373dc43c1650"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "057ea29a69986170db62cfa74698ae67e75fe810"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@057ea29a69986170db62cfa74698ae67e75fe810",
"title": "057ea29a69986170db62cfa74698ae67e75fe810",
"url": "https://fuchsia.googlesource.com/integration/+/057ea29a69986170db62cfa74698ae67e75fe810"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c0877d7b36aaf79d0f6e16c08d2d3958cfabdf9b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c0877d7b36aaf79d0f6e16c08d2d3958cfabdf9b",
"title": "c0877d7b36aaf79d0f6e16c08d2d3958cfabdf9b",
"url": "https://fuchsia.googlesource.com/integration/+/c0877d7b36aaf79d0f6e16c08d2d3958cfabdf9b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c9c1138be4d022ea3b33615463a26245ac43f59f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c9c1138be4d022ea3b33615463a26245ac43f59f",
"title": "c9c1138be4d022ea3b33615463a26245ac43f59f",
"url": "https://fuchsia.googlesource.com/integration/+/c9c1138be4d022ea3b33615463a26245ac43f59f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c9c1138be4d022ea3b33615463a26245ac43f59f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938537459058490320"
},
{
"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"
}
}
[23:23:43.016] Scheduled build:
{
"id": "8773107396983437473",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-10T23:23:42.631473390Z",
"updateTime": "2023-08-10T23:23:42.631473390Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c9c1138be4d022ea3b33615463a26245ac43f59f",
"ref": "refs/heads/main"
}
}
}
[23:23:43.021] Task URL: https://cr-buildbucket.appspot.com/build/8773107396983437473
[23:23:43.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:2:0) after 1m34s
[23:23:43.949] Received PubSub notification, asking Buildbucket for the build status
[23:23:44.025] Build status: SCHEDULED
[23:23:45.279] Received PubSub notification, asking Buildbucket for the build status
[23:23:45.300] Build status: STARTED
[23:25:17.438] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:2:0)
[23:25:17.534] Build status: STARTED
[23:25:17.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:5:0) after 4m58s
[23:30:15.569] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:5:0)
[23:30:15.596] Build status: STARTED
[23:30:15.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:6:0) after 7m59s
[23:38:14.812] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:6:0)
[23:38:14.831] Build status: STARTED
[23:38:14.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:7:0) after 4m25s
[23:42:39.847] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:7:0)
[23:42:39.936] Build status: STARTED
[23:42:39.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:8:0) after 7m54s
[23:50:34.050] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:8:0)
[23:50:34.073] Build status: STARTED
[23:50:34.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:9:0) after 6m16s
[23:56:50.090] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:9:0)
[23:56:50.121] Build status: STARTED
[23:56:50.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:10:0) after 8m8s
[00:04:58.249] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:10:0)
[00:04:58.277] Build status: STARTED
[00:04:58.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:11:0) after 1m39s
[00:06:37.374] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:11:0)
[00:06:37.392] Build status: STARTED
[00:06:37.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:12:0) after 4m58s
[00:11:36.139] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:12:0)
[00:11:36.161] Build status: STARTED
[00:11:36.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:13:0) after 1m36s
[00:13:12.355] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:13:0)
[00:13:12.390] Build status: STARTED
[00:13:12.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:14:0) after 4m57s
[00:18:09.410] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:14:0)
[00:18:09.430] Build status: STARTED
[00:18:09.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:15:0) after 5m3s
[00:23:12.797] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:15:0)
[00:23:12.826] Build status: STARTED
[00:23:12.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:16:0) after 3m0s
[00:26:13.189] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:16:0)
[00:26:13.207] Build status: STARTED
[00:26:13.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:17:0) after 6m54s
[00:33:07.293] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:17:0)
[00:33:07.318] Build status: STARTED
[00:33:07.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:18:0) after 6m8s
[00:39:15.374] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:18:0)
[00:39:15.403] Build status: STARTED
[00:39:15.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:19:0) after 1m55s
[00:41:10.532] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:19:0)
[00:41:10.551] Build status: STARTED
[00:41:10.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8938537459058490320:20:0) after 7m49s
[00:45:12.108] Received PubSub notification, asking Buildbucket for the build status
[00:45:12.197] Build:
{
"id": "8773107396983437473",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-10T23:23:42.631473390Z",
"startTime": "2023-08-10T23:23:44.619421Z",
"endTime": "2023-08-11T00:45:11.276547053Z",
"updateTime": "2023-08-11T00:45:11.276547053Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c9c1138be4d022ea3b33615463a26245ac43f59f",
"ref": "refs/heads/main"
}
}
}
[00:45:12.197] Invocation finished in 1h21m30.928936419s with status SUCCEEDED