[05:30:58.066] New invocation is queued and will start shortly
[05:30:59.166] Starting the invocation (attempt 1)
[05:30:59.225] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/9427f8b8852ee0a65e55f71535f332f501a3910b"
[05:30:59.225] Popped gitiles commit info from properties and tags
[05:30:59.225] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:30:59.225] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[05:30:59.226] Buildbucket request:
{
"requestId": "8986077759181643056",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "df7bcd8a9a4782bed36c5343f996bb390e84712b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@df7bcd8a9a4782bed36c5343f996bb390e84712b",
"title": "df7bcd8a9a4782bed36c5343f996bb390e84712b",
"url": "https://chromium.googlesource.com/infra/infra/+/df7bcd8a9a4782bed36c5343f996bb390e84712b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6fe0dba005313e09baf0cfc6a6f194f87a9da396"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6fe0dba005313e09baf0cfc6a6f194f87a9da396",
"title": "6fe0dba005313e09baf0cfc6a6f194f87a9da396",
"url": "https://chromium.googlesource.com/infra/infra/+/6fe0dba005313e09baf0cfc6a6f194f87a9da396"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f931d2105e87f5736444f5d9784cc994ded297cf"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f931d2105e87f5736444f5d9784cc994ded297cf",
"title": "f931d2105e87f5736444f5d9784cc994ded297cf",
"url": "https://chromium.googlesource.com/infra/infra/+/f931d2105e87f5736444f5d9784cc994ded297cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f4fbe4222c6a5354d9a23554656509244a910dc6"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@f4fbe4222c6a5354d9a23554656509244a910dc6",
"title": "f4fbe4222c6a5354d9a23554656509244a910dc6",
"url": "https://chromium.googlesource.com/infra/infra/+/f4fbe4222c6a5354d9a23554656509244a910dc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4164c0c685a385a849204257795987f2f7863573"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@4164c0c685a385a849204257795987f2f7863573",
"title": "4164c0c685a385a849204257795987f2f7863573",
"url": "https://chromium.googlesource.com/infra/infra/+/4164c0c685a385a849204257795987f2f7863573"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6d93449b5d6b4e6ff8059ec66d80ec62bb16ca84"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@6d93449b5d6b4e6ff8059ec66d80ec62bb16ca84",
"title": "6d93449b5d6b4e6ff8059ec66d80ec62bb16ca84",
"url": "https://chromium.googlesource.com/infra/infra/+/6d93449b5d6b4e6ff8059ec66d80ec62bb16ca84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "37c55e26344cd4d25eeca0376dc9f6d545b38fd1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@37c55e26344cd4d25eeca0376dc9f6d545b38fd1",
"title": "37c55e26344cd4d25eeca0376dc9f6d545b38fd1",
"url": "https://chromium.googlesource.com/infra/infra/+/37c55e26344cd4d25eeca0376dc9f6d545b38fd1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "9427f8b8852ee0a65e55f71535f332f501a3910b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/main@9427f8b8852ee0a65e55f71535f332f501a3910b",
"title": "9427f8b8852ee0a65e55f71535f332f501a3910b",
"url": "https://chromium.googlesource.com/infra/infra/+/9427f8b8852ee0a65e55f71535f332f501a3910b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9427f8b8852ee0a65e55f71535f332f501a3910b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8986077759181643056"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win11-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[05:31:00.247] Scheduled build:
{
"id": "8820647697154583745",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 500,
"createdBy": "project:infra",
"createTime": "2022-03-04T05:30:59.363589986Z",
"updateTime": "2022-03-04T05:30:59.363589986Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9427f8b8852ee0a65e55f71535f332f501a3910b",
"ref": "refs/heads/main"
}
}
}
[05:31:00.247] Task URL: https://cr-buildbucket-dev.appspot.com/build/8820647697154583745
[05:31:00.247] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:2:0) after 6m29s
[05:37:29.266] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:2:0)
[05:37:29.304] Build status: SCHEDULED
[05:37:29.304] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:3:0) after 3m16s
[05:40:45.322] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:3:0)
[05:40:45.365] Build status: SCHEDULED
[05:40:45.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:4:0) after 1m8s
[05:41:53.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:4:0)
[05:41:53.437] Build status: SCHEDULED
[05:41:53.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:5:0) after 3m7s
[05:45:00.457] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:5:0)
[05:45:00.493] Build status: SCHEDULED
[05:45:00.493] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:6:0) after 5m55s
[05:50:55.512] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:6:0)
[05:50:55.557] Build status: SCHEDULED
[05:50:55.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:7:0) after 1m30s
[05:52:25.579] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:7:0)
[05:52:25.618] Build status: SCHEDULED
[05:52:25.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:8:0) after 4m53s
[05:57:18.639] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:8:0)
[05:57:18.731] Build status: SCHEDULED
[05:57:18.731] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:9:0) after 4m54s
[06:02:12.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:9:0)
[06:02:12.804] Build status: SCHEDULED
[06:02:12.804] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:10:0) after 3m35s
[06:05:47.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:10:0)
[06:05:47.913] Build status: SCHEDULED
[06:05:47.913] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:11:0) after 8m48s
[06:14:35.934] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:11:0)
[06:14:35.979] Build status: SCHEDULED
[06:14:35.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:12:0) after 8m26s
[06:23:02.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:12:0)
[06:23:02.054] Build status: SCHEDULED
[06:23:02.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:13:0) after 5m18s
[06:28:20.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:13:0)
[06:28:20.104] Build status: SCHEDULED
[06:28:20.104] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:14:0) after 4m26s
[06:32:46.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:14:0)
[06:32:46.156] Build status: SCHEDULED
[06:32:46.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:15:0) after 6m58s
[06:39:44.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:15:0)
[06:39:44.240] Build status: SCHEDULED
[06:39:44.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:16:0) after 2m17s
[06:42:01.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:16:0)
[06:42:01.307] Build status: SCHEDULED
[06:42:01.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:17:0) after 8m27s
[06:50:28.329] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:17:0)
[06:50:28.388] Build status: SCHEDULED
[06:50:28.388] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:18:0) after 4m16s
[06:54:44.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:18:0)
[06:54:44.458] Build status: SCHEDULED
[06:54:44.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:19:0) after 4m25s
[06:59:09.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:19:0)
[06:59:09.537] Build status: SCHEDULED
[06:59:09.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:20:0) after 2m20s
[07:01:29.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:20:0)
[07:01:29.603] Build status: SCHEDULED
[07:01:29.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:21:0) after 3m28s
[07:04:57.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:21:0)
[07:04:57.680] Build status: SCHEDULED
[07:04:57.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:22:0) after 4m55s
[07:09:52.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:22:0)
[07:09:52.733] Build status: SCHEDULED
[07:09:52.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:23:0) after 5m31s
[07:13:55.525] Received PubSub notification, asking Buildbucket for the build status
[07:13:55.553] Build status: STARTED
[07:15:23.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:23:0)
[07:15:23.822] Build status: STARTED
[07:15:23.822] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:25:0) after 4m23s
[07:19:46.847] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:25:0)
[07:19:46.884] Build status: STARTED
[07:19:46.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:26:0) after 4m21s
[07:24:07.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:26:0)
[07:24:07.938] Build status: STARTED
[07:24:07.938] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:27:0) after 7m29s
[07:31:36.960] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:27:0)
[07:31:37.040] Build status: STARTED
[07:31:37.040] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:28:0) after 3m51s
[07:35:28.063] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:28:0)
[07:35:28.119] Build status: STARTED
[07:35:28.119] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:29:0) after 5m23s
[07:40:51.141] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:29:0)
[07:40:51.179] Build status: STARTED
[07:40:51.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win11-64:8986077759181643056:30:0) after 3m58s
[07:41:03.661] Received PubSub notification, asking Buildbucket for the build status
[07:41:03.703] Build:
{
"id": "8820647697154583745",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win11-64"
},
"number": 500,
"createdBy": "project:infra",
"createTime": "2022-03-04T05:30:59.363589986Z",
"startTime": "2022-03-04T07:13:52.207444Z",
"endTime": "2022-03-04T07:41:02.157039990Z",
"updateTime": "2022-03-04T07:41:02.157039990Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "9427f8b8852ee0a65e55f71535f332f501a3910b",
"ref": "refs/heads/main"
}
}
}
[07:41:03.703] Invocation finished in 2h10m5.655470815s with status SUCCEEDED