[20:44:08.139] New invocation is queued and will start shortly
[20:44:09.229] Starting the invocation (attempt 1)
[20:44:09.267] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/f15c1dc6d63d57d57cd3848862abf4cde6ddefb1"
[20:44:09.267] Popped gitiles commit info from properties and tags
[20:44:09.267] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:44:09.270] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:44:09.270] Buildbucket request:
{
"requestId": "9011478055181030288",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ab8ace36f75ce4c8df156b2d1d7e7f332fa89168"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ab8ace36f75ce4c8df156b2d1d7e7f332fa89168",
"title": "ab8ace36f75ce4c8df156b2d1d7e7f332fa89168",
"url": "https://chromium.googlesource.com/infra/infra/+/ab8ace36f75ce4c8df156b2d1d7e7f332fa89168"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a946fe06a85bcca36507a07ea8c5f645db094fad"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a946fe06a85bcca36507a07ea8c5f645db094fad",
"title": "a946fe06a85bcca36507a07ea8c5f645db094fad",
"url": "https://chromium.googlesource.com/infra/infra/+/a946fe06a85bcca36507a07ea8c5f645db094fad"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8ed429f8d0eb110c46cd515adf876c7b4cae5674"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8ed429f8d0eb110c46cd515adf876c7b4cae5674",
"title": "8ed429f8d0eb110c46cd515adf876c7b4cae5674",
"url": "https://chromium.googlesource.com/infra/infra/+/8ed429f8d0eb110c46cd515adf876c7b4cae5674"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a145294de74fe6896a19305c946ec0cc37c01482"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a145294de74fe6896a19305c946ec0cc37c01482",
"title": "a145294de74fe6896a19305c946ec0cc37c01482",
"url": "https://chromium.googlesource.com/infra/infra/+/a145294de74fe6896a19305c946ec0cc37c01482"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5645c66db4dbc62447dcc8490cc0c01cd64be0e4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5645c66db4dbc62447dcc8490cc0c01cd64be0e4",
"title": "5645c66db4dbc62447dcc8490cc0c01cd64be0e4",
"url": "https://chromium.googlesource.com/infra/infra/+/5645c66db4dbc62447dcc8490cc0c01cd64be0e4"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "fec98573717aa52ea37c760f2c36dddc653463b0"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fec98573717aa52ea37c760f2c36dddc653463b0",
"title": "fec98573717aa52ea37c760f2c36dddc653463b0",
"url": "https://chromium.googlesource.com/infra/infra/+/fec98573717aa52ea37c760f2c36dddc653463b0"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "7f1a4a1c39e7e376f360b734a8c37eb3219a30cf"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7f1a4a1c39e7e376f360b734a8c37eb3219a30cf",
"title": "7f1a4a1c39e7e376f360b734a8c37eb3219a30cf",
"url": "https://chromium.googlesource.com/infra/infra/+/7f1a4a1c39e7e376f360b734a8c37eb3219a30cf"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "031457dab3556e0fac0bda42c7044d06d6d917b5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@031457dab3556e0fac0bda42c7044d06d6d917b5",
"title": "031457dab3556e0fac0bda42c7044d06d6d917b5",
"url": "https://chromium.googlesource.com/infra/infra/+/031457dab3556e0fac0bda42c7044d06d6d917b5"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1a065dd77a733ae1cf4ed5cd1a9b9bbeb78543cb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1a065dd77a733ae1cf4ed5cd1a9b9bbeb78543cb",
"title": "1a065dd77a733ae1cf4ed5cd1a9b9bbeb78543cb",
"url": "https://chromium.googlesource.com/infra/infra/+/1a065dd77a733ae1cf4ed5cd1a9b9bbeb78543cb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8e3c133d75073aaa5032789f65a4c61619d72591"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8e3c133d75073aaa5032789f65a4c61619d72591",
"title": "8e3c133d75073aaa5032789f65a4c61619d72591",
"url": "https://chromium.googlesource.com/infra/infra/+/8e3c133d75073aaa5032789f65a4c61619d72591"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "fe5196c4d45c55109ce0b45d4debe4a896e57b90"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@fe5196c4d45c55109ce0b45d4debe4a896e57b90",
"title": "fe5196c4d45c55109ce0b45d4debe4a896e57b90",
"url": "https://chromium.googlesource.com/infra/infra/+/fe5196c4d45c55109ce0b45d4debe4a896e57b90"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f15c1dc6d63d57d57cd3848862abf4cde6ddefb1"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f15c1dc6d63d57d57cd3848862abf4cde6ddefb1",
"title": "f15c1dc6d63d57d57cd3848862abf4cde6ddefb1",
"url": "https://chromium.googlesource.com/infra/infra/+/f15c1dc6d63d57d57cd3848862abf4cde6ddefb1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f15c1dc6d63d57d57cd3848862abf4cde6ddefb1",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9011478055181030288"
},
{
"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"
}
}
[20:44:09.861] Scheduled build:
{
"id": "8846047993248830657",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 19533,
"createdBy": "project:infra",
"createTime": "2021-05-27T20:44:09.349221797Z",
"updateTime": "2021-05-27T20:44:09.640432827Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f15c1dc6d63d57d57cd3848862abf4cde6ddefb1",
"ref": "refs/heads/master"
}
},
"canary": true
}
[20:44:09.861] Task URL: https://cr-buildbucket-dev.appspot.com/build/8846047993248830657
[20:44:09.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:2:0) after 1m37s
[20:45:46.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:2:0)
[20:45:46.933] Build status: SCHEDULED
[20:45:46.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:3:0) after 1m51s
[20:47:37.951] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:3:0)
[20:47:37.983] Build status: SCHEDULED
[20:47:37.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:4:0) after 1m8s
[20:48:46.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:4:0)
[20:48:46.058] Build status: SCHEDULED
[20:48:46.058] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:5:0) after 1m24s
[20:50:10.087] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:5:0)
[20:50:10.120] Build status: SCHEDULED
[20:50:10.120] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:6:0) after 3m1s
[20:53:11.142] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:6:0)
[20:53:11.175] Build status: SCHEDULED
[20:53:11.175] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:7:0) after 2m10s
[20:55:21.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:7:0)
[20:55:21.352] Build status: SCHEDULED
[20:55:21.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:8:0) after 7m15s
[21:02:36.381] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:8:0)
[21:02:36.779] Build status: SCHEDULED
[21:02:36.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:9:0) after 9m43s
[21:12:19.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:9:0)
[21:12:19.832] Build status: SCHEDULED
[21:12:19.832] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:10:0) after 5m39s
[21:17:58.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:10:0)
[21:17:58.924] Build status: SCHEDULED
[21:17:58.924] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:11:0) after 5m52s
[21:23:50.977] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:11:0)
[21:23:51.016] Build status: SCHEDULED
[21:23:51.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:12:0) after 9m9s
[21:33:00.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:12:0)
[21:33:00.091] Build status: SCHEDULED
[21:33:00.091] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:13:0) after 5m19s
[21:38:19.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:13:0)
[21:38:19.148] Build status: SCHEDULED
[21:38:19.148] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:14:0) after 8m1s
[21:46:20.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:14:0)
[21:46:20.557] Build status: SCHEDULED
[21:46:20.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:15:0) after 4m4s
[21:50:24.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:15:0)
[21:50:24.623] Build status: SCHEDULED
[21:50:24.623] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:16:0) after 4m26s
[21:54:50.650] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:16:0)
[21:54:50.679] Build status: SCHEDULED
[21:54:50.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:17:0) after 7m54s
[22:02:44.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:17:0)
[22:02:44.722] Build status: STARTED
[22:02:44.722] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:18:0) after 5m47s
[22:08:31.945] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:18:0)
[22:08:31.990] Build status: STARTED
[22:08:31.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:19:0) after 3m3s
[22:11:35.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:19:0)
[22:11:35.115] Build status: STARTED
[22:11:35.115] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:20:0) after 8m31s
[22:20:06.163] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9011478055181030288:20:0)
[22:20:06.193] Build:
{
"id": "8846047993248830657",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 19533,
"createdBy": "project:infra",
"createTime": "2021-05-27T20:44:09.349221797Z",
"startTime": "2021-05-27T21:59:34.017432Z",
"endTime": "2021-05-27T22:13:59.458939Z",
"updateTime": "2021-05-27T22:14:15.283077Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "f15c1dc6d63d57d57cd3848862abf4cde6ddefb1",
"ref": "refs/heads/master"
}
},
"canary": true
}
[22:20:06.193] Invocation finished in 1h35m58.070412763s with status SUCCEEDED