[04:39:01.465] New invocation is queued and will start shortly
[04:39:02.754] Starting the invocation (attempt 1)
[04:39:02.833] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e521e2a26dbe69f37b3c6db26037d3d48b316cbc"
[04:39:02.833] Popped gitiles commit info from properties and tags
[04:39:02.833] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:39:02.833] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:39:02.833] Buildbucket request:
{
"requestId": "8946308958913403472",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e521e2a26dbe69f37b3c6db26037d3d48b316cbc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8946308958913403472"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMac (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:39:03.373] Scheduled build:
{
"id": "8780878896713695393",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 30565,
"createdBy": "project:chromium",
"createTime": "2023-05-17T04:39:02.938408550Z",
"updateTime": "2023-05-17T04:39:02.938408550Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e521e2a26dbe69f37b3c6db26037d3d48b316cbc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:39:03.373] Task URL: https://cr-buildbucket.appspot.com/build/8780878896713695393
[04:39:03.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:2:0) after 7m28s
[04:39:09.216] Received PubSub notification, asking Buildbucket for the build status
[04:39:09.242] Build status: STARTED
[04:46:31.507] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:2:0)
[04:46:31.530] Build status: STARTED
[04:46:31.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:4:0) after 6m16s
[04:52:47.558] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:4:0)
[04:52:47.601] Build status: STARTED
[04:52:47.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:5:0) after 6m42s
[04:59:29.717] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:5:0)
[04:59:29.744] Build status: STARTED
[04:59:29.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:6:0) after 1m34s
[05:01:03.865] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:6:0)
[05:01:03.886] Build status: STARTED
[05:01:03.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:7:0) after 6m6s
[05:07:10.242] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:7:0)
[05:07:10.264] Build status: STARTED
[05:07:10.264] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:8:0) after 7m43s
[05:14:53.326] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:8:0)
[05:14:53.344] Build status: STARTED
[05:14:53.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:9:0) after 5m43s
[05:20:36.443] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:9:0)
[05:20:36.488] Build status: STARTED
[05:20:36.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:10:0) after 9m43s
[05:30:19.720] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:10:0)
[05:30:19.742] Build status: STARTED
[05:30:19.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:11:0) after 5m23s
[05:35:42.846] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:11:0)
[05:35:42.865] Build status: STARTED
[05:35:42.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:12:0) after 3m48s
[05:39:31.014] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:12:0)
[05:39:31.038] Build status: STARTED
[05:39:31.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:13:0) after 2m19s
[05:41:50.065] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:13:0)
[05:41:50.120] Build status: STARTED
[05:41:50.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:14:0) after 8m52s
[05:50:42.225] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:14:0)
[05:50:42.247] Build status: STARTED
[05:50:42.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:15:0) after 8m59s
[05:59:41.597] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:15:0)
[05:59:41.622] Build status: STARTED
[05:59:41.622] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:16:0) after 8m50s
[06:08:31.731] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:16:0)
[06:08:31.749] Build status: STARTED
[06:08:31.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:17:0) after 5m10s
[06:13:41.801] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:17:0)
[06:13:41.826] Build status: STARTED
[06:13:41.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:18:0) after 1m49s
[06:15:30.871] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:18:0)
[06:15:30.889] Build status: STARTED
[06:15:30.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:19:0) after 7m24s
[06:22:55.082] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:19:0)
[06:22:55.110] Build status: STARTED
[06:22:55.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:20:0) after 5m18s
[06:28:13.142] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:20:0)
[06:28:13.170] Build status: STARTED
[06:28:13.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:21:0) after 9m40s
[06:37:53.237] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:21:0)
[06:37:53.262] Build status: STARTED
[06:37:53.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:22:0) after 5m30s
[06:43:23.354] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:22:0)
[06:43:23.381] Build status: STARTED
[06:43:23.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:23:0) after 4m49s
[06:48:12.482] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:23:0)
[06:48:12.500] Build status: STARTED
[06:48:12.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:24:0) after 1m29s
[06:49:41.529] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:24:0)
[06:49:41.545] Build status: STARTED
[06:49:41.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:25:0) after 3m21s
[06:53:02.671] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:25:0)
[06:53:02.693] Build status: STARTED
[06:53:02.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:26:0) after 7m52s
[07:00:54.691] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:26:0)
[07:00:54.722] Build status: STARTED
[07:00:54.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:27:0) after 5m3s
[07:05:57.761] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:27:0)
[07:05:57.891] Build status: STARTED
[07:05:57.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:28:0) after 1m26s
[07:07:24.043] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:28:0)
[07:07:24.065] Build status: STARTED
[07:07:24.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:29:0) after 5m53s
[07:13:17.108] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:29:0)
[07:13:17.150] Build status: STARTED
[07:13:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:30:0) after 8m55s
[07:22:12.289] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:30:0)
[07:22:12.308] Build status: STARTED
[07:22:12.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:31:0) after 2m19s
[07:24:31.341] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:31:0)
[07:24:31.359] Build status: STARTED
[07:24:31.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:32:0) after 6m49s
[07:31:20.353] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:32:0)
[07:31:20.487] Build status: STARTED
[07:31:20.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:33:0) after 7m38s
[07:38:58.904] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:33:0)
[07:38:58.925] Build status: STARTED
[07:38:58.925] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:34:0) after 9m55s
[07:48:53.966] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:34:0)
[07:48:53.981] Build status: STARTED
[07:48:53.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:35:0) after 9m20s
[07:58:14.139] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:35:0)
[07:58:14.159] Build status: STARTED
[07:58:14.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:36:0) after 3m6s
[08:01:20.214] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:36:0)
[08:01:20.266] Build status: STARTED
[08:01:20.266] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:37:0) after 9m31s
[08:10:51.305] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:37:0)
[08:10:51.323] Build status: STARTED
[08:10:51.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:38:0) after 6m39s
[08:17:30.400] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:38:0)
[08:17:30.421] Build status: STARTED
[08:17:30.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:39:0) after 4m41s
[08:22:11.710] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:39:0)
[08:22:11.730] Build status: STARTED
[08:22:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:40:0) after 1m23s
[08:23:35.103] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:40:0)
[08:23:35.131] Build status: STARTED
[08:23:35.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:41:0) after 3m39s
[08:27:14.164] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:41:0)
[08:27:14.184] Build status: STARTED
[08:27:14.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:42:0) after 3m58s
[08:31:12.624] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:42:0)
[08:31:12.649] Build status: STARTED
[08:31:12.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:43:0) after 4m30s
[08:35:42.688] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:43:0)
[08:35:42.712] Build status: STARTED
[08:35:42.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:44:0) after 4m47s
[08:40:29.748] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:44:0)
[08:40:29.776] Build status: STARTED
[08:40:29.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:45:0) after 3m56s
[08:44:25.998] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:45:0)
[08:44:26.021] Build status: STARTED
[08:44:26.021] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:46:0) after 9m11s
[08:53:37.089] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:46:0)
[08:53:37.112] Build status: STARTED
[08:53:37.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:47:0) after 5m54s
[08:59:31.138] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:47:0)
[08:59:31.181] Build status: STARTED
[08:59:31.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:48:0) after 8m44s
[09:08:15.350] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:48:0)
[09:08:15.399] Build status: STARTED
[09:08:15.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:49:0) after 9m29s
[09:17:44.445] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:49:0)
[09:17:44.463] Build status: STARTED
[09:17:44.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:50:0) after 8m56s
[09:26:40.501] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:50:0)
[09:26:40.563] Build status: STARTED
[09:26:40.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:51:0) after 5m44s
[09:32:24.725] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:51:0)
[09:32:24.745] Build status: STARTED
[09:32:24.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:52:0) after 4m56s
[09:37:20.802] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:52:0)
[09:37:20.827] Build status: STARTED
[09:37:20.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:53:0) after 4m25s
[09:41:46.121] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:53:0)
[09:41:46.150] Build status: STARTED
[09:41:46.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:54:0) after 1m36s
[09:43:22.300] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:54:0)
[09:43:22.319] Build status: STARTED
[09:43:22.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:55:0) after 8m23s
[09:51:45.555] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:55:0)
[09:51:45.574] Build status: STARTED
[09:51:45.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:56:0) after 3m15s
[09:55:00.606] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:56:0)
[09:55:00.626] Build status: STARTED
[09:55:00.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:57:0) after 9m51s
[10:04:51.852] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:57:0)
[10:04:51.873] Build status: STARTED
[10:04:51.873] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:58:0) after 8m11s
[10:13:02.915] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:58:0)
[10:13:02.938] Build status: STARTED
[10:13:02.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:59:0) after 9m16s
[10:22:19.128] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:59:0)
[10:22:19.151] Build status: STARTED
[10:22:19.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:60:0) after 3m12s
[10:25:31.391] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:60:0)
[10:25:31.413] Build status: STARTED
[10:25:31.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:61:0) after 7m29s
[10:33:00.497] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:61:0)
[10:33:00.513] Build status: STARTED
[10:33:00.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:62:0) after 2m0s
[10:35:00.640] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:62:0)
[10:35:00.677] Build status: STARTED
[10:35:00.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:63:0) after 2m43s
[10:37:43.743] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:63:0)
[10:37:43.772] Build status: STARTED
[10:37:43.772] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:64:0) after 2m56s
[10:40:39.820] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:64:0)
[10:40:39.839] Build status: STARTED
[10:40:39.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:65:0) after 8m48s
[10:49:28.501] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:65:0)
[10:49:28.542] Build status: STARTED
[10:49:28.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:66:0) after 7m51s
[10:57:19.845] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:66:0)
[10:57:19.865] Build status: STARTED
[10:57:19.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:67:0) after 3m54s
[11:01:13.923] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:67:0)
[11:01:13.951] Build status: STARTED
[11:01:13.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:68:0) after 5m49s
[11:07:03.268] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:68:0)
[11:07:03.288] Build status: STARTED
[11:07:03.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:69:0) after 2m37s
[11:09:40.281] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:69:0)
[11:09:40.320] Build status: STARTED
[11:09:40.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:70:0) after 5m10s
[11:14:50.350] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:70:0)
[11:14:50.367] Build status: STARTED
[11:14:50.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:71:0) after 4m50s
[11:19:40.466] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:71:0)
[11:19:40.494] Build status: STARTED
[11:19:40.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:72:0) after 6m29s
[11:26:09.605] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:72:0)
[11:26:09.625] Build status: STARTED
[11:26:09.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:73:0) after 2m30s
[11:28:39.656] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:73:0)
[11:28:39.702] Build status: STARTED
[11:28:39.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:74:0) after 3m50s
[11:32:29.792] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:74:0)
[11:32:29.814] Build status: STARTED
[11:32:29.814] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:75:0) after 6m0s
[11:38:29.968] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:75:0)
[11:38:29.988] Build status: STARTED
[11:38:29.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:76:0) after 3m38s
[11:42:08.023] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:76:0)
[11:42:08.040] Build status: STARTED
[11:42:08.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:77:0) after 2m13s
[11:44:21.091] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:77:0)
[11:44:21.109] Build status: STARTED
[11:44:21.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:78:0) after 3m54s
[11:48:15.356] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:78:0)
[11:48:15.404] Build status: STARTED
[11:48:15.404] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:79:0) after 6m48s
[11:55:03.533] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:79:0)
[11:55:03.554] Build status: STARTED
[11:55:03.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:80:0) after 1m37s
[11:56:40.589] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:80:0)
[11:56:40.608] Build status: STARTED
[11:56:40.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:81:0) after 2m9s
[11:58:49.626] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:81:0)
[11:58:49.647] Build status: STARTED
[11:58:49.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:82:0) after 2m49s
[12:01:38.817] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:82:0)
[12:01:38.849] Build status: STARTED
[12:01:38.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:83:0) after 3m32s
[12:05:11.126] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:83:0)
[12:05:11.151] Build status: STARTED
[12:05:11.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:84:0) after 6m4s
[12:11:15.351] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:84:0)
[12:11:15.373] Build status: STARTED
[12:11:15.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:85:0) after 6m24s
[12:17:39.783] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:85:0)
[12:17:39.814] Build status: STARTED
[12:17:39.814] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:86:0) after 4m7s
[12:21:47.078] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:86:0)
[12:21:47.114] Build status: STARTED
[12:21:47.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:87:0) after 4m12s
[12:25:59.232] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:87:0)
[12:25:59.276] Build status: STARTED
[12:25:59.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:88:0) after 6m30s
[12:32:29.312] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:88:0)
[12:32:29.328] Build status: STARTED
[12:32:29.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:89:0) after 6m30s
[12:38:59.399] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:89:0)
[12:38:59.422] Build status: STARTED
[12:38:59.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:90:0) after 4m16s
[12:43:15.883] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:90:0)
[12:43:15.902] Build status: STARTED
[12:43:15.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:91:0) after 5m19s
[12:48:34.891] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:91:0)
[12:48:34.913] Build status: STARTED
[12:48:34.913] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:92:0) after 3m7s
[12:51:41.951] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:92:0)
[12:51:41.970] Build status: STARTED
[12:51:41.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:93:0) after 1m55s
[12:53:37.057] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:93:0)
[12:53:37.077] Build status: STARTED
[12:53:37.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:94:0) after 4m4s
[12:57:41.336] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:94:0)
[12:57:41.355] Build status: STARTED
[12:57:41.355] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:95:0) after 1m13s
[12:58:54.773] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:95:0)
[12:58:54.845] Build status: STARTED
[12:58:54.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:96:0) after 1m50s
[13:00:44.951] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:96:0)
[13:00:44.996] Build status: STARTED
[13:00:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:97:0) after 2m21s
[13:03:06.058] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:97:0)
[13:03:06.094] Build status: STARTED
[13:03:06.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:98:0) after 1m16s
[13:04:22.091] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:98:0)
[13:04:22.116] Build status: STARTED
[13:04:22.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:99:0) after 5m39s
[13:10:01.196] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:99:0)
[13:10:01.223] Build status: STARTED
[13:10:01.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:100:0) after 5m15s
[13:15:16.255] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:100:0)
[13:15:16.273] Build status: STARTED
[13:15:16.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:101:0) after 2m26s
[13:17:42.310] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:101:0)
[13:17:42.329] Build status: STARTED
[13:17:42.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:102:0) after 1m55s
[13:19:37.839] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:102:0)
[13:19:37.867] Build status: STARTED
[13:19:37.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:103:0) after 6m3s
[13:25:40.916] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:103:0)
[13:25:40.952] Build status: STARTED
[13:25:40.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:104:0) after 1m27s
[13:27:08.090] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:104:0)
[13:27:08.110] Build status: STARTED
[13:27:08.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:105:0) after 3m9s
[13:30:17.143] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:105:0)
[13:30:17.166] Build status: STARTED
[13:30:17.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:106:0) after 3m42s
[13:33:59.314] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:106:0)
[13:33:59.347] Build status: STARTED
[13:33:59.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:107:0) after 2m57s
[13:36:56.683] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:107:0)
[13:36:56.704] Build status: STARTED
[13:36:56.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:108:0) after 3m7s
[13:40:04.765] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:108:0)
[13:40:04.784] Build status: STARTED
[13:40:04.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:109:0) after 5m58s
[13:46:02.812] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:109:0)
[13:46:02.832] Build status: STARTED
[13:46:02.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:110:0) after 5m14s
[13:51:16.985] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:110:0)
[13:51:17.007] Build status: STARTED
[13:51:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:111:0) after 6m50s
[13:58:07.381] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:111:0)
[13:58:07.441] Build status: STARTED
[13:58:07.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:112:0) after 6m30s
[14:04:38.223] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:112:0)
[14:04:38.243] Build status: STARTED
[14:04:38.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:113:0) after 6m11s
[14:10:49.264] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:113:0)
[14:10:49.279] Build status: STARTED
[14:10:49.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:114:0) after 6m32s
[14:17:21.447] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:114:0)
[14:17:21.473] Build status: STARTED
[14:17:21.473] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:115:0) after 3m29s
[14:20:51.093] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:115:0)
[14:20:51.148] Build status: STARTED
[14:20:51.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:116:0) after 1m2s
[14:21:53.209] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:116:0)
[14:21:53.236] Build status: STARTED
[14:21:53.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:117:0) after 5m7s
[14:27:00.285] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:117:0)
[14:27:00.306] Build status: STARTED
[14:27:00.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:118:0) after 9m30s
[14:36:30.442] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:118:0)
[14:36:30.458] Build status: STARTED
[14:36:30.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:119:0) after 3m36s
[14:40:06.616] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:119:0)
[14:40:06.648] Build status: STARTED
[14:40:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:120:0) after 8m0s
[14:48:06.678] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:120:0)
[14:48:06.695] Build status: STARTED
[14:48:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:121:0) after 4m24s
[14:52:30.752] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:121:0)
[14:52:30.793] Build status: STARTED
[14:52:30.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:122:0) after 8m45s
[15:01:16.096] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:122:0)
[15:01:16.129] Build status: STARTED
[15:01:16.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:123:0) after 9m6s
[15:10:22.160] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:123:0)
[15:10:22.176] Build status: STARTED
[15:10:22.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:124:0) after 8m36s
[15:18:58.237] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:124:0)
[15:18:58.262] Build status: STARTED
[15:18:58.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:125:0) after 1m6s
[15:20:04.296] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:125:0)
[15:20:04.315] Build status: STARTED
[15:20:04.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:126:0) after 8m33s
[15:28:37.416] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:126:0)
[15:28:37.585] Build status: STARTED
[15:28:37.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:127:0) after 2m46s
[15:31:23.619] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:127:0)
[15:31:23.636] Build status: STARTED
[15:31:23.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:128:0) after 9m2s
[15:40:25.998] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:128:0)
[15:40:26.018] Build status: STARTED
[15:40:26.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:129:0) after 9m48s
[15:50:14.148] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:129:0)
[15:50:14.188] Build status: STARTED
[15:50:14.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8946308958913403472:130:0) after 8m13s
[15:56:31.872] Received PubSub notification, asking Buildbucket for the build status
[15:56:31.890] Build:
{
"id": "8780878896713695393",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 30565,
"createdBy": "project:chromium",
"createTime": "2023-05-17T04:39:02.938408550Z",
"startTime": "2023-05-17T04:39:08.587933Z",
"endTime": "2023-05-17T15:56:31.468327634Z",
"updateTime": "2023-05-17T15:56:31.468327634Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e521e2a26dbe69f37b3c6db26037d3d48b316cbc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:56:31.890] Invocation finished in 11h17m30.432746417s with status FAILED