[23:08:42.049] New invocation is queued and will start shortly
[23:08:43.112] Starting the invocation (attempt 1)
[23:08:43.141] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8e9cbb28130f2a71a171ddd76aa9cedb6a331e70"
[23:08:43.141] Popped gitiles commit info from properties and tags
[23:08:43.142] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:08:43.143] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:08:43.144] Buildbucket request:
{
"requestId": "8898766333714128640",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8898766333714128640",
"job": "infra/infra-continuous-win10-64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3b047be2b3e02c04d09c0f10d9c63abfdfbb7814"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@3b047be2b3e02c04d09c0f10d9c63abfdfbb7814",
"title": "3b047be2b3e02c04d09c0f10d9c63abfdfbb7814",
"url": "https://chromium.googlesource.com/infra/infra/+/3b047be2b3e02c04d09c0f10d9c63abfdfbb7814"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1ae212461b1644a8b79b167aea86a9c24be50605"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@1ae212461b1644a8b79b167aea86a9c24be50605",
"title": "1ae212461b1644a8b79b167aea86a9c24be50605",
"url": "https://chromium.googlesource.com/infra/infra/+/1ae212461b1644a8b79b167aea86a9c24be50605"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2ba0f8414fd6ea54e16b0c3bb9f65b13aff646b0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@2ba0f8414fd6ea54e16b0c3bb9f65b13aff646b0",
"title": "2ba0f8414fd6ea54e16b0c3bb9f65b13aff646b0",
"url": "https://chromium.googlesource.com/infra/infra/+/2ba0f8414fd6ea54e16b0c3bb9f65b13aff646b0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b23fa08c9e3e3c5310e45e5e0c69124cac0a45bb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@b23fa08c9e3e3c5310e45e5e0c69124cac0a45bb",
"title": "b23fa08c9e3e3c5310e45e5e0c69124cac0a45bb",
"url": "https://chromium.googlesource.com/infra/infra/+/b23fa08c9e3e3c5310e45e5e0c69124cac0a45bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "556739e9b60f7bc398c987cebb6eac0ae6a7a5ae"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@556739e9b60f7bc398c987cebb6eac0ae6a7a5ae",
"title": "556739e9b60f7bc398c987cebb6eac0ae6a7a5ae",
"url": "https://chromium.googlesource.com/infra/infra/+/556739e9b60f7bc398c987cebb6eac0ae6a7a5ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a7f1107d5a72032c0394b81cc179f77d77dcf0c9"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@a7f1107d5a72032c0394b81cc179f77d77dcf0c9",
"title": "a7f1107d5a72032c0394b81cc179f77d77dcf0c9",
"url": "https://chromium.googlesource.com/infra/infra/+/a7f1107d5a72032c0394b81cc179f77d77dcf0c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f5d643d5ae84088b6c2919f7d452075bcf72e1b7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f5d643d5ae84088b6c2919f7d452075bcf72e1b7",
"title": "f5d643d5ae84088b6c2919f7d452075bcf72e1b7",
"url": "https://chromium.googlesource.com/infra/infra/+/f5d643d5ae84088b6c2919f7d452075bcf72e1b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8e9cbb28130f2a71a171ddd76aa9cedb6a331e70"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@8e9cbb28130f2a71a171ddd76aa9cedb6a331e70",
"title": "8e9cbb28130f2a71a171ddd76aa9cedb6a331e70",
"url": "https://chromium.googlesource.com/infra/infra/+/8e9cbb28130f2a71a171ddd76aa9cedb6a331e70"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "8e9cbb28130f2a71a171ddd76aa9cedb6a331e70",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898766333714128640"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[23:08:44.185] Scheduled build:
{
"id": "8733336271849653569",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 30660,
"createdBy": "project:infra",
"createTime": "2024-10-22T23:08:43.205773183Z",
"updateTime": "2024-10-22T23:08:43.205773183Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "8e9cbb28130f2a71a171ddd76aa9cedb6a331e70",
"ref": "refs/heads/main"
}
}
}
[23:08:44.185] Task URL: https://cr-buildbucket-dev.appspot.com/build/8733336271849653569
[23:08:44.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:2:0) after 6m15s
[23:08:44.742] Received PubSub notification, asking Buildbucket for the build status
[23:08:44.773] Build status: SCHEDULED
[23:14:59.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:2:0)
[23:14:59.223] Build status: SCHEDULED
[23:14:59.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:4:0) after 3m51s
[23:18:50.241] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:4:0)
[23:18:50.279] Build status: SCHEDULED
[23:18:50.279] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:5:0) after 7m4s
[23:25:54.295] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:5:0)
[23:25:54.328] Build status: SCHEDULED
[23:25:54.328] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:6:0) after 3m34s
[23:29:28.346] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:6:0)
[23:29:28.410] Build status: SCHEDULED
[23:29:28.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:7:0) after 9m3s
[23:38:31.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:7:0)
[23:38:31.459] Build status: SCHEDULED
[23:38:31.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:8:0) after 9m54s
[23:48:25.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:8:0)
[23:48:25.511] Build status: SCHEDULED
[23:48:25.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:9:0) after 9m16s
[23:57:41.528] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:9:0)
[23:57:41.569] Build status: SCHEDULED
[23:57:41.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:10:0) after 4m21s
[00:02:02.586] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:10:0)
[00:02:02.621] Build status: SCHEDULED
[00:02:02.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:11:0) after 2m42s
[00:04:44.639] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:11:0)
[00:04:44.669] Build status: SCHEDULED
[00:04:44.669] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:12:0) after 3m53s
[00:08:37.687] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:12:0)
[00:08:37.750] Build status: SCHEDULED
[00:08:37.750] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:13:0) after 4m30s
[00:13:07.769] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:13:0)
[00:13:07.824] Build status: SCHEDULED
[00:13:07.824] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:14:0) after 3m59s
[00:17:06.840] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:14:0)
[00:17:06.866] Build status: SCHEDULED
[00:17:06.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:15:0) after 5m18s
[00:22:24.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:15:0)
[00:22:24.971] Build status: SCHEDULED
[00:22:24.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:16:0) after 9m17s
[00:31:42.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:16:0)
[00:31:42.898] Build status: SCHEDULED
[00:31:42.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:17:0) after 8m35s
[00:40:17.915] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:17:0)
[00:40:17.951] Build status: SCHEDULED
[00:40:17.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:18:0) after 5m38s
[00:43:38.713] Received PubSub notification, asking Buildbucket for the build status
[00:43:38.735] Build status: STARTED
[00:45:56.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:18:0)
[00:45:56.037] Build status: STARTED
[00:45:56.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:20:0) after 6m1s
[00:51:57.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:20:0)
[00:51:57.106] Build status: STARTED
[00:51:57.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:21:0) after 4m18s
[00:56:15.129] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:21:0)
[00:56:15.175] Build status: STARTED
[00:56:15.175] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:8898766333714128640:22:0) after 6m48s
[00:58:57.083] Received PubSub notification, asking Buildbucket for the build status
[00:58:57.118] Build:
{
"id": "8733336271849653569",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 30660,
"createdBy": "project:infra",
"createTime": "2024-10-22T23:08:43.205773183Z",
"startTime": "2024-10-23T00:43:38.473719009Z",
"endTime": "2024-10-23T00:58:56.696444615Z",
"updateTime": "2024-10-23T00:58:56.696444615Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "8e9cbb28130f2a71a171ddd76aa9cedb6a331e70",
"ref": "refs/heads/main"
}
}
}
[00:58:57.118] Invocation finished in 1h50m15.07400469s with status SUCCEEDED