[00:18:32.668] New invocation is queued and will start shortly
[00:18:33.969] Starting the invocation (attempt 1)
[00:18:34.006] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a221e0b9717bd26d782e631e93ef172853919e1"
[00:18:34.006] Popped gitiles commit info from properties and tags
[00:18:34.006] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:18:34.007] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:18:34.007] Buildbucket request:
{
"requestId": "9016537995862236800",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a221e0b9717bd26d782e631e93ef172853919e1",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9016537995862236800"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:18:34.629] Scheduled build:
{
"id": "8851107933705241552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 7142,
"createdBy": "project:chromium",
"createTime": "2021-04-02T00:18:34.093823Z",
"updateTime": "2021-04-02T00:18:34.551773Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a221e0b9717bd26d782e631e93ef172853919e1",
"ref": "refs/heads/master"
}
}
}
[00:18:34.629] Task URL: https://cr-buildbucket.appspot.com/build/8851107933705241552
[00:18:34.629] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:2:0) after 5m56s
[00:22:29.065] Received PubSub notification, asking Buildbucket for the build status
[00:22:29.106] Build status: STARTED
[00:24:30.736] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:2:0)
[00:24:30.765] Build status: STARTED
[00:24:30.765] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:4:0) after 6m36s
[00:31:06.879] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:4:0)
[00:31:06.915] Build status: STARTED
[00:31:06.915] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:5:0) after 6m47s
[00:37:54.063] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:5:0)
[00:37:54.094] Build status: STARTED
[00:37:54.094] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:6:0) after 8m9s
[00:46:03.203] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:6:0)
[00:46:03.235] Build status: STARTED
[00:46:03.235] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:7:0) after 4m39s
[00:50:42.245] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:7:0)
[00:50:42.273] Build status: STARTED
[00:50:42.273] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:8:0) after 3m36s
[00:54:18.572] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:8:0)
[00:54:18.609] Build status: STARTED
[00:54:18.609] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:9:0) after 4m56s
[00:59:14.952] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:9:0)
[00:59:14.981] Build status: STARTED
[00:59:14.981] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:10:0) after 6m53s
[01:06:08.049] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:10:0)
[01:06:08.084] Build status: STARTED
[01:06:08.084] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:11:0) after 6m0s
[01:12:08.475] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:11:0)
[01:12:08.634] Build status: STARTED
[01:12:08.635] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:12:0) after 2m54s
[01:15:02.740] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:12:0)
[01:15:02.771] Build status: STARTED
[01:15:02.771] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:13:0) after 4m24s
[01:19:26.752] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:13:0)
[01:19:26.779] Build status: STARTED
[01:19:26.779] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:14:0) after 5m57s
[01:25:23.915] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:14:0)
[01:25:23.941] Build status: STARTED
[01:25:23.941] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:15:0) after 5m14s
[01:30:38.117] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:15:0)
[01:30:38.142] Build status: STARTED
[01:30:38.142] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:16:0) after 1m48s
[01:32:26.326] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:16:0)
[01:32:26.355] Build status: STARTED
[01:32:26.355] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:17:0) after 5m25s
[01:37:51.383] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:17:0)
[01:37:51.412] Build status: STARTED
[01:37:51.412] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:18:0) after 3m1s
[01:40:52.523] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:18:0)
[01:40:52.552] Build status: STARTED
[01:40:52.552] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:19:0) after 7m27s
[01:48:19.856] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:19:0)
[01:48:19.896] Build status: STARTED
[01:48:19.896] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:20:0) after 4m4s
[01:52:23.932] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:20:0)
[01:52:23.960] Build status: STARTED
[01:52:23.960] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:21:0) after 6m25s
[01:58:49.199] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:21:0)
[01:58:49.233] Build status: STARTED
[01:58:49.233] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:22:0) after 2m38s
[02:01:27.443] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:22:0)
[02:01:27.471] Build status: STARTED
[02:01:27.471] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:23:0) after 9m38s
[02:11:05.593] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:23:0)
[02:11:05.643] Build status: STARTED
[02:11:05.643] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:24:0) after 4m52s
[02:15:57.979] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:24:0)
[02:15:58.011] Build status: STARTED
[02:15:58.011] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:25:0) after 9m32s
[02:25:30.205] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:25:0)
[02:25:30.276] Build status: STARTED
[02:25:30.276] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:26:0) after 1m51s
[02:27:21.698] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:26:0)
[02:27:21.742] Build status: STARTED
[02:27:21.742] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:27:0) after 1m8s
[02:28:29.771] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:27:0)
[02:28:29.818] Build status: STARTED
[02:28:29.818] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:28:0) after 4m45s
[02:33:15.029] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:28:0)
[02:33:15.067] Build status: STARTED
[02:33:15.067] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:29:0) after 6m20s
[02:39:35.321] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:29:0)
[02:39:35.352] Build status: STARTED
[02:39:35.352] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:30:0) after 4m17s
[02:43:52.401] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:30:0)
[02:43:52.431] Build status: STARTED
[02:43:52.431] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:31:0) after 8m41s
[02:52:33.840] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:31:0)
[02:52:33.884] Build status: STARTED
[02:52:33.884] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:32:0) after 8m59s
[03:01:33.130] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:32:0)
[03:01:33.161] Build status: STARTED
[03:01:33.161] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:33:0) after 7m44s
[03:09:17.183] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:33:0)
[03:09:17.213] Build status: STARTED
[03:09:17.213] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:34:0) after 9m21s
[03:18:38.262] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:34:0)
[03:18:38.289] Build status: STARTED
[03:18:38.289] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:35:0) after 5m4s
[03:23:42.573] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:35:0)
[03:23:42.616] Build status: STARTED
[03:23:42.616] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:36:0) after 8m40s
[03:32:22.650] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:36:0)
[03:32:22.682] Build status: STARTED
[03:32:22.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:37:0) after 5m0s
[03:37:22.713] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:37:0)
[03:37:22.746] Build status: STARTED
[03:37:22.746] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:38:0) after 1m39s
[03:39:01.775] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:38:0)
[03:39:01.808] Build status: STARTED
[03:39:01.808] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:39:0) after 7m24s
[03:46:25.835] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:39:0)
[03:46:25.868] Build status: STARTED
[03:46:25.868] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:40:0) after 7m17s
[03:53:42.956] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:40:0)
[03:53:42.983] Build status: STARTED
[03:53:42.983] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:41:0) after 3m14s
[03:56:57.012] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:41:0)
[03:56:57.042] Build status: STARTED
[03:56:57.042] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:42:0) after 5m33s
[04:02:30.069] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:42:0)
[04:02:30.097] Build status: STARTED
[04:02:30.097] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:43:0) after 2m23s
[04:04:53.181] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:43:0)
[04:04:53.226] Build status: STARTED
[04:04:53.226] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:44:0) after 3m15s
[04:08:08.358] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:44:0)
[04:08:08.405] Build status: STARTED
[04:08:08.405] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:45:0) after 9m14s
[04:17:22.547] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:45:0)
[04:17:22.595] Build status: STARTED
[04:17:22.595] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:46:0) after 5m30s
[04:22:52.752] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:46:0)
[04:22:52.778] Build status: STARTED
[04:22:52.778] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:47:0) after 8m52s
[04:31:44.813] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:47:0)
[04:31:44.867] Build status: STARTED
[04:31:44.867] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:48:0) after 5m28s
[04:37:12.950] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:48:0)
[04:37:12.979] Build status: STARTED
[04:37:12.979] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:49:0) after 1m49s
[04:39:02.044] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:49:0)
[04:39:02.087] Build status: STARTED
[04:39:02.087] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:50:0) after 6m35s
[04:45:37.316] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:50:0)
[04:45:37.355] Build status: STARTED
[04:45:37.355] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:51:0) after 3m43s
[04:49:20.416] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:51:0)
[04:49:20.455] Build status: STARTED
[04:49:20.455] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:52:0) after 4m56s
[04:54:16.498] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:52:0)
[04:54:16.538] Build status: STARTED
[04:54:16.538] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:53:0) after 9m36s
[05:03:52.818] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:53:0)
[05:03:52.851] Build status: STARTED
[05:03:52.851] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:54:0) after 9m7s
[05:12:59.898] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:54:0)
[05:12:59.925] Build status: STARTED
[05:12:59.925] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:55:0) after 3m35s
[05:16:34.999] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:55:0)
[05:16:35.027] Build status: STARTED
[05:16:35.027] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:56:0) after 1m52s
[05:18:27.056] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:56:0)
[05:18:27.088] Build status: STARTED
[05:18:27.088] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:57:0) after 3m23s
[05:21:50.183] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:57:0)
[05:21:50.212] Build status: STARTED
[05:21:50.212] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:58:0) after 2m28s
[05:24:18.251] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:58:0)
[05:24:18.278] Build status: STARTED
[05:24:18.278] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:59:0) after 9m20s
[05:33:38.301] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:59:0)
[05:33:38.363] Build status: STARTED
[05:33:38.363] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:60:0) after 9m52s
[05:43:30.473] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:60:0)
[05:43:30.508] Build status: STARTED
[05:43:30.508] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:61:0) after 2m24s
[05:45:54.537] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:61:0)
[05:45:54.566] Build status: STARTED
[05:45:54.566] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:62:0) after 9m31s
[05:55:25.595] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:62:0)
[05:55:25.624] Build status: STARTED
[05:55:25.624] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:63:0) after 8m18s
[06:03:43.672] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:63:0)
[06:03:43.704] Build status: STARTED
[06:03:43.704] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:64:0) after 1m29s
[06:05:12.731] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:64:0)
[06:05:12.762] Build status: STARTED
[06:05:12.762] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:65:0) after 6m46s
[06:11:58.942] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:65:0)
[06:11:58.975] Build status: STARTED
[06:11:58.975] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:66:0) after 2m25s
[06:14:23.948] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:66:0)
[06:14:23.972] Build status: STARTED
[06:14:23.972] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:67:0) after 3m13s
[06:17:37.138] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:67:0)
[06:17:37.175] Build status: STARTED
[06:17:37.175] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:68:0) after 3m10s
[06:20:47.258] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:68:0)
[06:20:47.283] Build status: STARTED
[06:20:47.283] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:69:0) after 9m32s
[06:30:20.211] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:69:0)
[06:30:20.238] Build status: STARTED
[06:30:20.238] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:70:0) after 5m2s
[06:35:22.263] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:70:0)
[06:35:22.306] Build status: STARTED
[06:35:22.306] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:71:0) after 8m11s
[06:43:33.416] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:71:0)
[06:43:33.462] Build status: STARTED
[06:43:33.462] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:72:0) after 7m54s
[06:51:27.543] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:72:0)
[06:51:27.573] Build status: STARTED
[06:51:27.573] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:73:0) after 2m15s
[06:53:42.666] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:73:0)
[06:53:42.716] Build status: STARTED
[06:53:42.716] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:74:0) after 9m50s
[07:03:32.844] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:74:0)
[07:03:32.890] Build status: STARTED
[07:03:32.890] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:75:0) after 3m59s
[07:07:31.999] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:75:0)
[07:07:32.028] Build status: STARTED
[07:07:32.028] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:76:0) after 9m47s
[07:17:19.054] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:76:0)
[07:17:19.092] Build status: STARTED
[07:17:19.092] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:77:0) after 7m57s
[07:25:16.351] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:77:0)
[07:25:16.381] Build status: STARTED
[07:25:16.381] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:78:0) after 7m9s
[07:32:25.473] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:78:0)
[07:32:25.502] Build status: STARTED
[07:32:25.502] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:79:0) after 3m21s
[07:35:46.499] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:79:0)
[07:35:46.529] Build status: STARTED
[07:35:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:80:0) after 5m28s
[07:41:15.368] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:80:0)
[07:41:15.405] Build status: STARTED
[07:41:15.405] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:81:0) after 8m10s
[07:49:25.439] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:81:0)
[07:49:25.509] Build status: STARTED
[07:49:25.509] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:82:0) after 7m55s
[07:57:20.552] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:82:0)
[07:57:20.597] Build status: STARTED
[07:57:20.597] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:83:0) after 5m34s
[08:02:54.713] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:83:0)
[08:02:54.740] Build status: STARTED
[08:02:54.740] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:84:0) after 6m29s
[08:09:23.810] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:84:0)
[08:09:23.838] Build status: STARTED
[08:09:23.838] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:85:0) after 1m42s
[08:11:05.868] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:85:0)
[08:11:05.909] Build status: STARTED
[08:11:05.909] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:86:0) after 5m4s
[08:16:09.934] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:86:0)
[08:16:09.987] Build status: STARTED
[08:16:09.987] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:87:0) after 9m48s
[08:25:58.019] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:87:0)
[08:25:58.053] Build status: STARTED
[08:25:58.053] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:88:0) after 6m59s
[08:32:57.080] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:88:0)
[08:32:57.115] Build status: STARTED
[08:32:57.115] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:89:0) after 7m53s
[08:40:50.139] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:89:0)
[08:40:50.170] Build status: STARTED
[08:40:50.170] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:90:0) after 2m38s
[08:43:28.155] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:90:0)
[08:43:28.186] Build status: STARTED
[08:43:28.186] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9016537995862236800:91:0) after 8m25s
[08:49:33.999] Received PubSub notification, asking Buildbucket for the build status
[08:49:34.024] Build:
{
"id": "8851107933705241552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 7142,
"createdBy": "project:chromium",
"createTime": "2021-04-02T00:18:34.093823Z",
"startTime": "2021-04-02T00:22:26.046011Z",
"endTime": "2021-04-02T08:49:32.621004922Z",
"updateTime": "2021-04-02T08:49:32.621719303Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a221e0b9717bd26d782e631e93ef172853919e1",
"ref": "refs/heads/master"
}
}
}
[08:49:34.024] Invocation finished in 8h31m1.371349183s with status FAILED