{"level":"warn","ts":"2026-07-14T17:32:27.716032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:32:27.716124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.107:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.107:2380","--initial-cluster=instance=https://199.19.213.107:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.107:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.107:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T17:32:27.716196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:32:27.716209Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-07-14T17:32:27.716224Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T17:32:27.716826Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"]} {"level":"info","ts":"2026-07-14T17:32:27.716892Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.107:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T17:32:27.720673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.567406ms"} {"level":"info","ts":"2026-07-14T17:32:27.726635Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be75c9626aba3f77","cluster-id":"c32d1248c0d0c6d"} {"level":"info","ts":"2026-07-14T17:32:27.726706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:32:27.726728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 0"} {"level":"info","ts":"2026-07-14T17:32:27.726742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be75c9626aba3f77 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:32:27.72675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 1"} {"level":"info","ts":"2026-07-14T17:32:27.726791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"warn","ts":"2026-07-14T17:32:27.731645Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:32:27.733028Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:32:27.734245Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T17:32:27.736339Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be75c9626aba3f77","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:32:27.736507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be75c9626aba3f77","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:32:27.736519Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:32:27.737036Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:32:27.737122Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:32:27.74006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"info","ts":"2026-07-14T17:32:27.741914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","added-peer-id":"be75c9626aba3f77","added-peer-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-07-14T17:32:27.742383Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T17:32:27.74255Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-07-14T17:32:27.742605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-07-14T17:32:27.743846Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be75c9626aba3f77","initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:32:27.743897Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:32:28.527206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:32:28.527259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:32:28.527289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgPreVoteResp from be75c9626aba3f77 at term 1"} {"level":"info","ts":"2026-07-14T17:32:28.527312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:32:28.527326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgVoteResp from be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-07-14T17:32:28.527344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became leader at term 2"} {"level":"info","ts":"2026-07-14T17:32:28.52736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be75c9626aba3f77 elected leader be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-07-14T17:32:28.5283Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:28.529501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:28.52959Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:28.529621Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:28.529659Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be75c9626aba3f77","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.107:2379]}","request-path":"/0/members/be75c9626aba3f77/attributes","cluster-id":"c32d1248c0d0c6d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:32:28.529683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:32:28.53013Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:32:28.530269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:32:28.53032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:32:28.531464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:32:28.543004Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.107:2379"} {"level":"info","ts":"2026-07-14T17:33:03.78733Z","caller":"traceutil/trace.go:171","msg":"trace[858799306] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"279.540922ms","start":"2026-07-14T17:33:03.507752Z","end":"2026-07-14T17:33:03.787293Z","steps":["trace[858799306] 'process raft request' (duration: 271.254325ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.787676Z","caller":"traceutil/trace.go:171","msg":"trace[1164993543] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"268.786592ms","start":"2026-07-14T17:33:03.518855Z","end":"2026-07-14T17:33:03.787642Z","steps":["trace[1164993543] 'process raft request' (duration: 268.44498ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.787822Z","caller":"traceutil/trace.go:171","msg":"trace[1893590713] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"261.59818ms","start":"2026-07-14T17:33:03.526216Z","end":"2026-07-14T17:33:03.787814Z","steps":["trace[1893590713] 'process raft request' (duration: 261.27508ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.787829Z","caller":"traceutil/trace.go:171","msg":"trace[2002630464] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"158.553181ms","start":"2026-07-14T17:33:03.629239Z","end":"2026-07-14T17:33:03.787792Z","steps":["trace[2002630464] 'process raft request' (duration: 158.445697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.787895Z","caller":"traceutil/trace.go:171","msg":"trace[1974332106] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"254.946858ms","start":"2026-07-14T17:33:03.532906Z","end":"2026-07-14T17:33:03.787853Z","steps":["trace[1974332106] 'process raft request' (duration: 254.645178ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.787821Z","caller":"traceutil/trace.go:171","msg":"trace[436182730] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"268.791752ms","start":"2026-07-14T17:33:03.518973Z","end":"2026-07-14T17:33:03.787764Z","steps":["trace[436182730] 'process raft request' (duration: 268.43353ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:03.788084Z","caller":"traceutil/trace.go:171","msg":"trace[1058224020] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"279.815051ms","start":"2026-07-14T17:33:03.507886Z","end":"2026-07-14T17:33:03.787701Z","steps":["trace[1058224020] 'process raft request' (duration: 279.324504ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:10.239931Z","caller":"traceutil/trace.go:171","msg":"trace[2046358962] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"155.796584ms","start":"2026-07-14T17:33:10.084107Z","end":"2026-07-14T17:33:10.239904Z","steps":["trace[2046358962] 'read index received' (duration: 149.845739ms)","trace[2046358962] 'applied index is now lower than readState.Index' (duration: 5.949825ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:33:10.240086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.959639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:33:10.240142Z","caller":"traceutil/trace.go:171","msg":"trace[1625949824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"156.064342ms","start":"2026-07-14T17:33:10.084067Z","end":"2026-07-14T17:33:10.240131Z","steps":["trace[1625949824] 'agreement among raft nodes before linearized reading' (duration: 155.943859ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:10.240114Z","caller":"traceutil/trace.go:171","msg":"trace[128714019] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"205.866388ms","start":"2026-07-14T17:33:10.034214Z","end":"2026-07-14T17:33:10.24008Z","steps":["trace[128714019] 'process raft request' (duration: 199.810859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:10.7823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.619006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:33:10.782501Z","caller":"traceutil/trace.go:171","msg":"trace[510749812] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"528.817963ms","start":"2026-07-14T17:33:10.253644Z","end":"2026-07-14T17:33:10.782462Z","steps":["trace[510749812] 'process raft request' (duration: 408.33231ms)","trace[510749812] 'compare' (duration: 119.356178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:33:10.782662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:10.253631Z","time spent":"528.967637ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:33:10.783257Z","caller":"traceutil/trace.go:171","msg":"trace[2111264543] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"369.716314ms","start":"2026-07-14T17:33:10.413527Z","end":"2026-07-14T17:33:10.783243Z","steps":["trace[2111264543] 'read index received' (duration: 248.392006ms)","trace[2111264543] 'applied index is now lower than readState.Index' (duration: 121.322738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:33:10.783785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.239668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T17:33:10.783857Z","caller":"traceutil/trace.go:171","msg":"trace[2003742459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"262.349721ms","start":"2026-07-14T17:33:10.521488Z","end":"2026-07-14T17:33:10.783838Z","steps":["trace[2003742459] 'agreement among raft nodes before linearized reading' (duration: 262.098004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:10.786052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.510172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bzmb2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T17:33:10.786131Z","caller":"traceutil/trace.go:171","msg":"trace[990793321] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bzmb2; range_end:; response_count:1; response_revision:637; }","duration":"372.636916ms","start":"2026-07-14T17:33:10.413477Z","end":"2026-07-14T17:33:10.786114Z","steps":["trace[990793321] 'agreement among raft nodes before linearized reading' (duration: 369.89175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:10.78618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:10.413459Z","time spent":"372.711558ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-bzmb2\" "} {"level":"info","ts":"2026-07-14T17:33:10.99809Z","caller":"traceutil/trace.go:171","msg":"trace[954163052] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"209.973197ms","start":"2026-07-14T17:33:10.78808Z","end":"2026-07-14T17:33:10.998054Z","steps":["trace[954163052] 'process raft request' (duration: 199.488759ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:10.998171Z","caller":"traceutil/trace.go:171","msg":"trace[612799431] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"208.368476ms","start":"2026-07-14T17:33:10.789781Z","end":"2026-07-14T17:33:10.998149Z","steps":["trace[612799431] 'process raft request' (duration: 208.314764ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:10.998307Z","caller":"traceutil/trace.go:171","msg":"trace[1824822371] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"209.832222ms","start":"2026-07-14T17:33:10.788304Z","end":"2026-07-14T17:33:10.998136Z","steps":["trace[1824822371] 'process raft request' (duration: 209.699388ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:10.998683Z","caller":"traceutil/trace.go:171","msg":"trace[1841419746] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"209.41574ms","start":"2026-07-14T17:33:10.789249Z","end":"2026-07-14T17:33:10.998665Z","steps":["trace[1841419746] 'process raft request' (duration: 208.814291ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:11.00585Z","caller":"traceutil/trace.go:171","msg":"trace[417854308] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:660; }","duration":"101.860441ms","start":"2026-07-14T17:33:10.903975Z","end":"2026-07-14T17:33:11.005835Z","steps":["trace[417854308] 'read index received' (duration: 83.569689ms)","trace[417854308] 'applied index is now lower than readState.Index' (duration: 18.289882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:33:11.00607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.082458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:33:11.00607Z","caller":"traceutil/trace.go:171","msg":"trace[479002557] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"205.799997ms","start":"2026-07-14T17:33:10.800225Z","end":"2026-07-14T17:33:11.006025Z","steps":["trace[479002557] 'process raft request' (duration: 205.527928ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:11.006134Z","caller":"traceutil/trace.go:171","msg":"trace[1788466553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"102.162061ms","start":"2026-07-14T17:33:10.903935Z","end":"2026-07-14T17:33:11.006097Z","steps":["trace[1788466553] 'agreement among raft nodes before linearized reading' (duration: 101.958125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:23.163372Z","caller":"traceutil/trace.go:171","msg":"trace[898518166] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"112.535918ms","start":"2026-07-14T17:33:23.050794Z","end":"2026-07-14T17:33:23.16333Z","steps":["trace[898518166] 'process raft request' (duration: 110.910974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:25.954888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.874206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-plst9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-14T17:33:25.954985Z","caller":"traceutil/trace.go:171","msg":"trace[1237497330] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-plst9; range_end:; response_count:1; response_revision:793; }","duration":"450.02123ms","start":"2026-07-14T17:33:25.504937Z","end":"2026-07-14T17:33:25.954958Z","steps":["trace[1237497330] 'range keys from in-memory index tree' (duration: 449.565598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:25.955056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:25.504918Z","time spent":"450.117163ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-plst9\" "} {"level":"info","ts":"2026-07-14T17:33:26.071341Z","caller":"traceutil/trace.go:171","msg":"trace[1521324643] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"110.735798ms","start":"2026-07-14T17:33:25.960581Z","end":"2026-07-14T17:33:26.071317Z","steps":["trace[1521324643] 'process raft request' (duration: 110.425119ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:17.581702Z","caller":"traceutil/trace.go:171","msg":"trace[1940926880] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"126.433586ms","start":"2026-07-14T17:34:17.455241Z","end":"2026-07-14T17:34:17.581674Z","steps":["trace[1940926880] 'process raft request' (duration: 126.374375ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:17.581716Z","caller":"traceutil/trace.go:171","msg":"trace[1515313561] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"127.580967ms","start":"2026-07-14T17:34:17.454096Z","end":"2026-07-14T17:34:17.581677Z","steps":["trace[1515313561] 'process raft request' (duration: 126.358245ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:30.969601Z","caller":"traceutil/trace.go:171","msg":"trace[1630291258] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"163.058922ms","start":"2026-07-14T17:34:30.806514Z","end":"2026-07-14T17:34:30.969573Z","steps":["trace[1630291258] 'process raft request' (duration: 109.075818ms)","trace[1630291258] 'compare' (duration: 53.529506ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:43.65825Z","caller":"traceutil/trace.go:171","msg":"trace[1334986719] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"303.891278ms","start":"2026-07-14T17:34:43.354326Z","end":"2026-07-14T17:34:43.658218Z","steps":["trace[1334986719] 'read index received' (duration: 303.657834ms)","trace[1334986719] 'applied index is now lower than readState.Index' (duration: 232.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:43.658309Z","caller":"traceutil/trace.go:171","msg":"trace[2075876270] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"339.908925ms","start":"2026-07-14T17:34:43.318359Z","end":"2026-07-14T17:34:43.658268Z","steps":["trace[2075876270] 'process raft request' (duration: 339.714252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:43.658542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.180402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43067d77-1c7e-4663-8db3-c82c48c724aa\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T17:34:43.658589Z","caller":"traceutil/trace.go:171","msg":"trace[2074576202] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43067d77-1c7e-4663-8db3-c82c48c724aa; range_end:; response_count:1; response_revision:1868; }","duration":"304.274895ms","start":"2026-07-14T17:34:43.354301Z","end":"2026-07-14T17:34:43.658576Z","steps":["trace[2074576202] 'agreement among raft nodes before linearized reading' (duration: 304.071641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:43.658629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:43.35429Z","time spent":"304.331025ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43067d77-1c7e-4663-8db3-c82c48c724aa\" "} {"level":"warn","ts":"2026-07-14T17:34:43.65879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:43.31834Z","time spent":"340.059858ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:34:43.875781Z","caller":"traceutil/trace.go:171","msg":"trace[1207003457] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"119.990674ms","start":"2026-07-14T17:34:43.755765Z","end":"2026-07-14T17:34:43.875756Z","steps":["trace[1207003457] 'process raft request' (duration: 99.639943ms)","trace[1207003457] 'compare' (duration: 20.144698ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:43.876076Z","caller":"traceutil/trace.go:171","msg":"trace[815766730] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"120.207608ms","start":"2026-07-14T17:34:43.755838Z","end":"2026-07-14T17:34:43.876045Z","steps":["trace[815766730] 'process raft request' (duration: 119.847832ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:43.8762Z","caller":"traceutil/trace.go:171","msg":"trace[456951725] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"119.274931ms","start":"2026-07-14T17:34:43.756905Z","end":"2026-07-14T17:34:43.87618Z","steps":["trace[456951725] 'process raft request' (duration: 119.113058ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:45.057102Z","caller":"traceutil/trace.go:171","msg":"trace[1967238600] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"108.1244ms","start":"2026-07-14T17:34:44.948949Z","end":"2026-07-14T17:34:45.057073Z","steps":["trace[1967238600] 'process raft request' (duration: 107.955497ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:52.120561Z","caller":"traceutil/trace.go:171","msg":"trace[1092866981] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"124.074957ms","start":"2026-07-14T17:34:51.996456Z","end":"2026-07-14T17:34:52.12053Z","steps":["trace[1092866981] 'process raft request' (duration: 113.793296ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:52.120588Z","caller":"traceutil/trace.go:171","msg":"trace[691876796] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"123.729921ms","start":"2026-07-14T17:34:51.996829Z","end":"2026-07-14T17:34:52.120559Z","steps":["trace[691876796] 'process raft request' (duration: 123.573939ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:56.191857Z","caller":"traceutil/trace.go:171","msg":"trace[157433668] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2045; }","duration":"128.319623ms","start":"2026-07-14T17:34:56.063511Z","end":"2026-07-14T17:34:56.191831Z","steps":["trace[157433668] 'read index received' (duration: 128.195591ms)","trace[157433668] 'applied index is now lower than readState.Index' (duration: 122.842µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:34:56.192042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.507666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:34:56.192026Z","caller":"traceutil/trace.go:171","msg":"trace[1352632410] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"160.866126ms","start":"2026-07-14T17:34:56.03112Z","end":"2026-07-14T17:34:56.191986Z","steps":["trace[1352632410] 'process raft request' (duration: 160.529831ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:56.192087Z","caller":"traceutil/trace.go:171","msg":"trace[1463979234] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1970; }","duration":"128.602638ms","start":"2026-07-14T17:34:56.063469Z","end":"2026-07-14T17:34:56.192071Z","steps":["trace[1463979234] 'agreement among raft nodes before linearized reading' (duration: 128.513756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:56.192243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.955823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:34:56.19231Z","caller":"traceutil/trace.go:171","msg":"trace[1919756936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"107.029244ms","start":"2026-07-14T17:34:56.085264Z","end":"2026-07-14T17:34:56.192293Z","steps":["trace[1919756936] 'agreement among raft nodes before linearized reading' (duration: 106.925182ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:12.759537Z","caller":"traceutil/trace.go:171","msg":"trace[787090712] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"114.551746ms","start":"2026-07-14T17:35:12.644954Z","end":"2026-07-14T17:35:12.759505Z","steps":["trace[787090712] 'read index received' (duration: 114.247502ms)","trace[787090712] 'applied index is now lower than readState.Index' (duration: 302.994µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:12.759625Z","caller":"traceutil/trace.go:171","msg":"trace[1636841613] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"128.088595ms","start":"2026-07-14T17:35:12.6315Z","end":"2026-07-14T17:35:12.759589Z","steps":["trace[1636841613] 'process raft request' (duration: 127.793801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.759793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.77404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:35:12.759849Z","caller":"traceutil/trace.go:171","msg":"trace[1186213712] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2077; }","duration":"114.934062ms","start":"2026-07-14T17:35:12.644901Z","end":"2026-07-14T17:35:12.759835Z","steps":["trace[1186213712] 'agreement among raft nodes before linearized reading' (duration: 114.744819ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:18.859852Z","caller":"traceutil/trace.go:171","msg":"trace[2003281987] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"110.814093ms","start":"2026-07-14T17:35:18.749022Z","end":"2026-07-14T17:35:18.859837Z","steps":["trace[2003281987] 'process raft request' (duration: 108.777084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:36.576394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.298169ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T17:36:21.853555Z","caller":"traceutil/trace.go:171","msg":"trace[266005609] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"212.009692ms","start":"2026-07-14T17:36:21.641514Z","end":"2026-07-14T17:36:21.853524Z","steps":["trace[266005609] 'process raft request' (duration: 209.529281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:36:54.191336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.683884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:36:54.19143Z","caller":"traceutil/trace.go:171","msg":"trace[969644787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2730; }","duration":"104.811088ms","start":"2026-07-14T17:36:54.0866Z","end":"2026-07-14T17:36:54.191411Z","steps":["trace[969644787] 'range keys from in-memory index tree' (duration: 104.622012ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:36:54.426381Z","caller":"traceutil/trace.go:171","msg":"trace[315491599] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"112.517844ms","start":"2026-07-14T17:36:54.313831Z","end":"2026-07-14T17:36:54.426348Z","steps":["trace[315491599] 'process raft request' (duration: 112.216994ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:36:54.653765Z","caller":"traceutil/trace.go:171","msg":"trace[1563556138] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2834; }","duration":"113.856336ms","start":"2026-07-14T17:36:54.539884Z","end":"2026-07-14T17:36:54.65374Z","steps":["trace[1563556138] 'read index received' (duration: 113.66351ms)","trace[1563556138] 'applied index is now lower than readState.Index' (duration: 192.026µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:36:54.654032Z","caller":"traceutil/trace.go:171","msg":"trace[530756570] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"223.570322ms","start":"2026-07-14T17:36:54.430423Z","end":"2026-07-14T17:36:54.653993Z","steps":["trace[530756570] 'process raft request' (duration: 223.186679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:36:54.654635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.739814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:36:54.654741Z","caller":"traceutil/trace.go:171","msg":"trace[222450286] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2732; }","duration":"114.826357ms","start":"2026-07-14T17:36:54.539845Z","end":"2026-07-14T17:36:54.654672Z","steps":["trace[222450286] 'agreement among raft nodes before linearized reading' (duration: 113.994871ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:35.727265Z","caller":"traceutil/trace.go:171","msg":"trace[1990707078] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"114.584757ms","start":"2026-07-14T17:39:35.612655Z","end":"2026-07-14T17:39:35.72724Z","steps":["trace[1990707078] 'process raft request' (duration: 100.706031ms)","trace[1990707078] 'compare' (duration: 13.744623ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:35.727699Z","caller":"traceutil/trace.go:171","msg":"trace[187783030] transaction","detail":"{read_only:false; response_revision:3677; number_of_response:1; }","duration":"114.939474ms","start":"2026-07-14T17:39:35.612734Z","end":"2026-07-14T17:39:35.727674Z","steps":["trace[187783030] 'process raft request' (duration: 114.461005ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:35.727803Z","caller":"traceutil/trace.go:171","msg":"trace[1398296061] transaction","detail":"{read_only:false; response_revision:3678; number_of_response:1; }","duration":"114.475195ms","start":"2026-07-14T17:39:35.613308Z","end":"2026-07-14T17:39:35.727783Z","steps":["trace[1398296061] 'process raft request' (duration: 114.167709ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:50.767476Z","caller":"traceutil/trace.go:171","msg":"trace[29947282] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"101.686934ms","start":"2026-07-14T17:39:50.665735Z","end":"2026-07-14T17:39:50.767422Z","steps":["trace[29947282] 'process raft request' (duration: 101.437439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:33.725637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.4792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:40:33.725748Z","caller":"traceutil/trace.go:171","msg":"trace[1646648235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4429; }","duration":"275.631163ms","start":"2026-07-14T17:40:33.450097Z","end":"2026-07-14T17:40:33.725728Z","steps":["trace[1646648235] 'range keys from in-memory index tree' (duration: 275.318037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.024845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4573299188530345355,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:40:34.101062Z","caller":"traceutil/trace.go:171","msg":"trace[262933771] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"592.520659ms","start":"2026-07-14T17:40:33.508514Z","end":"2026-07-14T17:40:34.101035Z","steps":["trace[262933771] 'process raft request' (duration: 592.145073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.101218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.508487Z","time spent":"592.650782ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:34.121054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.088987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T17:40:34.121107Z","caller":"traceutil/trace.go:171","msg":"trace[1242477203] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4432; }","duration":"597.186008ms","start":"2026-07-14T17:40:33.52391Z","end":"2026-07-14T17:40:34.121096Z","steps":["trace[1242477203] 'agreement among raft nodes before linearized reading' (duration: 597.040386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.121145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.523893Z","time spent":"597.23461ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:40:34.120912Z","caller":"traceutil/trace.go:171","msg":"trace[119158904] linearizableReadLoop","detail":"{readStateIndex:4605; appliedIndex:4602; }","duration":"596.805002ms","start":"2026-07-14T17:40:33.523951Z","end":"2026-07-14T17:40:34.120756Z","steps":["trace[119158904] 'read index received' (duration: 576.982879ms)","trace[119158904] 'applied index is now lower than readState.Index' (duration: 19.821123ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:34.122117Z","caller":"traceutil/trace.go:171","msg":"trace[1686070006] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"276.304531ms","start":"2026-07-14T17:40:33.844564Z","end":"2026-07-14T17:40:34.120868Z","steps":["trace[1686070006] 'process raft request' (duration: 275.63874ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.122253Z","caller":"traceutil/trace.go:171","msg":"trace[1646538690] transaction","detail":"{read_only:false; response_revision:4432; number_of_response:1; }","duration":"106.009226ms","start":"2026-07-14T17:40:34.014866Z","end":"2026-07-14T17:40:34.120876Z","steps":["trace[1646538690] 'process raft request' (duration: 105.477487ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.250041Z","caller":"traceutil/trace.go:171","msg":"trace[353899511] linearizableReadLoop","detail":"{readStateIndex:4606; appliedIndex:4605; }","duration":"127.927037ms","start":"2026-07-14T17:40:34.12208Z","end":"2026-07-14T17:40:34.250007Z","steps":["trace[353899511] 'read index received' (duration: 127.759204ms)","trace[353899511] 'applied index is now lower than readState.Index' (duration: 166.273µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:34.250216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.533211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-14T17:40:34.250262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.788628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:40:34.250289Z","caller":"traceutil/trace.go:171","msg":"trace[663925284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4433; }","duration":"227.650894ms","start":"2026-07-14T17:40:34.022616Z","end":"2026-07-14T17:40:34.250267Z","steps":["trace[663925284] 'agreement among raft nodes before linearized reading' (duration: 227.39529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.250309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.176947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:40:34.250336Z","caller":"traceutil/trace.go:171","msg":"trace[714881263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4433; }","duration":"287.93049ms","start":"2026-07-14T17:40:33.962386Z","end":"2026-07-14T17:40:34.250317Z","steps":["trace[714881263] 'agreement among raft nodes before linearized reading' (duration: 287.708206ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.250351Z","caller":"traceutil/trace.go:171","msg":"trace[1013363802] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4433; }","duration":"521.245568ms","start":"2026-07-14T17:40:33.729094Z","end":"2026-07-14T17:40:34.25034Z","steps":["trace[1013363802] 'agreement among raft nodes before linearized reading' (duration: 521.039995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.250395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.728912Z","time spent":"521.472462ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-14T17:40:34.250402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.874912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T17:40:34.250476Z","caller":"traceutil/trace.go:171","msg":"trace[184047588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4433; }","duration":"516.985723ms","start":"2026-07-14T17:40:33.733471Z","end":"2026-07-14T17:40:34.250457Z","steps":["trace[184047588] 'agreement among raft nodes before linearized reading' (duration: 516.705919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:34.250511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.542642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T17:40:34.250528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:33.733454Z","time spent":"517.056494ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:40:34.250543Z","caller":"traceutil/trace.go:171","msg":"trace[831466837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4433; }","duration":"231.600543ms","start":"2026-07-14T17:40:34.018933Z","end":"2026-07-14T17:40:34.250534Z","steps":["trace[831466837] 'agreement among raft nodes before linearized reading' (duration: 231.510011ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.250589Z","caller":"traceutil/trace.go:171","msg":"trace[1058121378] transaction","detail":"{read_only:false; response_revision:4433; number_of_response:1; }","duration":"139.846713ms","start":"2026-07-14T17:40:34.11073Z","end":"2026-07-14T17:40:34.250577Z","steps":["trace[1058121378] 'process raft request' (duration: 139.05593ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.349071Z","caller":"traceutil/trace.go:171","msg":"trace[1957474420] transaction","detail":"{read_only:false; response_revision:4434; number_of_response:1; }","duration":"221.6406ms","start":"2026-07-14T17:40:34.127401Z","end":"2026-07-14T17:40:34.349041Z","steps":["trace[1957474420] 'process raft request' (duration: 221.518268ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.427775Z","caller":"traceutil/trace.go:171","msg":"trace[593820101] transaction","detail":"{read_only:false; response_revision:4435; number_of_response:1; }","duration":"170.487673ms","start":"2026-07-14T17:40:34.257266Z","end":"2026-07-14T17:40:34.427754Z","steps":["trace[593820101] 'process raft request' (duration: 170.31784ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.428128Z","caller":"traceutil/trace.go:171","msg":"trace[894469567] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"170.764859ms","start":"2026-07-14T17:40:34.257337Z","end":"2026-07-14T17:40:34.428102Z","steps":["trace[894469567] 'process raft request' (duration: 170.366201ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.428217Z","caller":"traceutil/trace.go:171","msg":"trace[683319446] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"170.512604ms","start":"2026-07-14T17:40:34.257679Z","end":"2026-07-14T17:40:34.428191Z","steps":["trace[683319446] 'process raft request' (duration: 170.277069ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.428329Z","caller":"traceutil/trace.go:171","msg":"trace[2145564067] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"166.983193ms","start":"2026-07-14T17:40:34.261334Z","end":"2026-07-14T17:40:34.428317Z","steps":["trace[2145564067] 'process raft request' (duration: 166.7783ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:34.428477Z","caller":"traceutil/trace.go:171","msg":"trace[176576665] transaction","detail":"{read_only:false; response_revision:4438; number_of_response:1; }","duration":"169.357585ms","start":"2026-07-14T17:40:34.259112Z","end":"2026-07-14T17:40:34.42847Z","steps":["trace[176576665] 'process raft request' (duration: 168.948208ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.333484Z","caller":"traceutil/trace.go:171","msg":"trace[1184720561] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"116.561423ms","start":"2026-07-14T17:40:40.216832Z","end":"2026-07-14T17:40:40.333393Z","steps":["trace[1184720561] 'process raft request' (duration: 116.362919ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.567375Z","caller":"traceutil/trace.go:171","msg":"trace[839641315] linearizableReadLoop","detail":"{readStateIndex:4766; appliedIndex:4765; }","duration":"109.240847ms","start":"2026-07-14T17:40:40.45811Z","end":"2026-07-14T17:40:40.567351Z","steps":["trace[839641315] 'read index received' (duration: 109.097955ms)","trace[839641315] 'applied index is now lower than readState.Index' (duration: 141.752µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:40.567432Z","caller":"traceutil/trace.go:171","msg":"trace[499603277] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"109.347299ms","start":"2026-07-14T17:40:40.458047Z","end":"2026-07-14T17:40:40.567394Z","steps":["trace[499603277] 'process raft request' (duration: 109.149686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:40.567656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.518032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T17:40:40.567749Z","caller":"traceutil/trace.go:171","msg":"trace[2145619519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4588; }","duration":"109.631543ms","start":"2026-07-14T17:40:40.45808Z","end":"2026-07-14T17:40:40.567712Z","steps":["trace[2145619519] 'agreement among raft nodes before linearized reading' (duration: 109.391149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:40.611823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.916978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-14T17:40:40.611856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.283011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-14T17:40:40.611892Z","caller":"traceutil/trace.go:171","msg":"trace[802546935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4589; }","duration":"153.019949ms","start":"2026-07-14T17:40:40.458857Z","end":"2026-07-14T17:40:40.611877Z","steps":["trace[802546935] 'agreement among raft nodes before linearized reading' (duration: 152.798945ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.6119Z","caller":"traceutil/trace.go:171","msg":"trace[2141360069] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4589; }","duration":"136.361733ms","start":"2026-07-14T17:40:40.475525Z","end":"2026-07-14T17:40:40.611887Z","steps":["trace[2141360069] 'agreement among raft nodes before linearized reading' (duration: 136.220831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:40.611929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.94523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T17:40:40.611996Z","caller":"traceutil/trace.go:171","msg":"trace[1687538852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4589; }","duration":"127.059673ms","start":"2026-07-14T17:40:40.484917Z","end":"2026-07-14T17:40:40.611977Z","steps":["trace[1687538852] 'agreement among raft nodes before linearized reading' (duration: 126.798128ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.61219Z","caller":"traceutil/trace.go:171","msg":"trace[3561601] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"153.287224ms","start":"2026-07-14T17:40:40.45889Z","end":"2026-07-14T17:40:40.612177Z","steps":["trace[3561601] 'process raft request' (duration: 152.658954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:42.904404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.846947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:42.904667Z","caller":"traceutil/trace.go:171","msg":"trace[230478868] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"282.903437ms","start":"2026-07-14T17:40:42.621746Z","end":"2026-07-14T17:40:42.904649Z","steps":["trace[230478868] 'process raft request' (duration: 282.742044ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.904713Z","caller":"traceutil/trace.go:171","msg":"trace[1610855603] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"283.404265ms","start":"2026-07-14T17:40:42.621277Z","end":"2026-07-14T17:40:42.904681Z","steps":["trace[1610855603] 'process raft request' (duration: 133.208223ms)","trace[1610855603] 'compare' (duration: 149.572672ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:42.904604Z","caller":"traceutil/trace.go:171","msg":"trace[172242277] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4784; }","duration":"277.863631ms","start":"2026-07-14T17:40:42.626722Z","end":"2026-07-14T17:40:42.904586Z","steps":["trace[172242277] 'read index received' (duration: 127.814301ms)","trace[172242277] 'applied index is now lower than readState.Index' (duration: 150.04852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:42.905005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.283549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T17:40:42.905028Z","caller":"traceutil/trace.go:171","msg":"trace[1752339911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4607; }","duration":"278.33986ms","start":"2026-07-14T17:40:42.626678Z","end":"2026-07-14T17:40:42.905018Z","steps":["trace[1752339911] 'agreement among raft nodes before linearized reading' (duration: 278.248479ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.916548Z","caller":"traceutil/trace.go:171","msg":"trace[1364752317] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"101.473929ms","start":"2026-07-14T17:40:42.815038Z","end":"2026-07-14T17:40:42.916512Z","steps":["trace[1364752317] 'process raft request' (duration: 101.320607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:42.916656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.43349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6460ac06-3cb9-45b0-8a76-61f21f615490\" ","response":"range_response_count:1 size:4053"} {"level":"warn","ts":"2026-07-14T17:40:42.916638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.118895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-14T17:40:42.916697Z","caller":"traceutil/trace.go:171","msg":"trace[1771371829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6460ac06-3cb9-45b0-8a76-61f21f615490; range_end:; response_count:1; response_revision:4608; }","duration":"198.511122ms","start":"2026-07-14T17:40:42.718174Z","end":"2026-07-14T17:40:42.916685Z","steps":["trace[1771371829] 'agreement among raft nodes before linearized reading' (duration: 198.340569ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.916722Z","caller":"traceutil/trace.go:171","msg":"trace[1174446417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4608; }","duration":"205.212457ms","start":"2026-07-14T17:40:42.711494Z","end":"2026-07-14T17:40:42.916706Z","steps":["trace[1174446417] 'agreement among raft nodes before linearized reading' (duration: 204.987213ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:43.032857Z","caller":"traceutil/trace.go:171","msg":"trace[343808134] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"112.485637ms","start":"2026-07-14T17:40:42.920342Z","end":"2026-07-14T17:40:43.032827Z","steps":["trace[343808134] 'process raft request' (duration: 112.278764ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:48.501037Z","caller":"traceutil/trace.go:171","msg":"trace[2138070103] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"120.885915ms","start":"2026-07-14T17:40:48.380122Z","end":"2026-07-14T17:40:48.501008Z","steps":["trace[2138070103] 'process raft request' (duration: 115.150228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.922668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.888136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:40:54.922773Z","caller":"traceutil/trace.go:171","msg":"trace[1218130570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4851; }","duration":"141.02927ms","start":"2026-07-14T17:40:54.781719Z","end":"2026-07-14T17:40:54.922748Z","steps":["trace[1218130570] 'range keys from in-memory index tree' (duration: 140.737233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.924345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.230041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-14T17:40:54.924425Z","caller":"traceutil/trace.go:171","msg":"trace[282907205] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4851; }","duration":"269.332303ms","start":"2026-07-14T17:40:54.655074Z","end":"2026-07-14T17:40:54.924406Z","steps":["trace[282907205] 'range keys from in-memory index tree' (duration: 267.768787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.157182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.991268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-14T17:40:59.157212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.849146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"info","ts":"2026-07-14T17:40:59.157245Z","caller":"traceutil/trace.go:171","msg":"trace[1909362173] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4877; }","duration":"594.089949ms","start":"2026-07-14T17:40:58.563138Z","end":"2026-07-14T17:40:59.157228Z","steps":["trace[1909362173] 'range keys from in-memory index tree' (duration: 593.854966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.157278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:58.563119Z","time spent":"594.151261ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:40:59.157291Z","caller":"traceutil/trace.go:171","msg":"trace[1178923675] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4877; }","duration":"459.980598ms","start":"2026-07-14T17:40:58.697284Z","end":"2026-07-14T17:40:59.157264Z","steps":["trace[1178923675] 'range keys from in-memory index tree' (duration: 459.632832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.15734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:58.697266Z","time spent":"460.06476ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2125,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-07-14T17:40:59.157704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.405886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-07-14T17:40:59.157743Z","caller":"traceutil/trace.go:171","msg":"trace[958726851] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4877; }","duration":"441.475627ms","start":"2026-07-14T17:40:58.716258Z","end":"2026-07-14T17:40:59.157734Z","steps":["trace[958726851] 'range keys from in-memory index tree' (duration: 441.280544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.15777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:58.716241Z","time spent":"441.520708ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10418,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T17:40:59.157957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.308924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T17:40:59.157995Z","caller":"traceutil/trace.go:171","msg":"trace[1127912577] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4877; }","duration":"432.367284ms","start":"2026-07-14T17:40:58.725616Z","end":"2026-07-14T17:40:59.157983Z","steps":["trace[1127912577] 'range keys from in-memory index tree' (duration: 432.193851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.158024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:58.725602Z","time spent":"432.410965ms","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T17:40:59.158165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.447866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:40:59.158252Z","caller":"traceutil/trace.go:171","msg":"trace[1470368141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4877; }","duration":"214.539137ms","start":"2026-07-14T17:40:58.94369Z","end":"2026-07-14T17:40:59.15823Z","steps":["trace[1470368141] 'range keys from in-memory index tree' (duration: 214.262673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.15841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.772247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:59.158525Z","caller":"traceutil/trace.go:171","msg":"trace[395476069] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4877; }","duration":"349.933221ms","start":"2026-07-14T17:40:58.808574Z","end":"2026-07-14T17:40:59.158507Z","steps":["trace[395476069] 'count revisions from in-memory index tree' (duration: 349.657156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.1586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:58.808552Z","time spent":"350.033242ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:40:59.158759Z","caller":"traceutil/trace.go:171","msg":"trace[706629712] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"198.228495ms","start":"2026-07-14T17:40:58.960516Z","end":"2026-07-14T17:40:59.158744Z","steps":["trace[706629712] 'process raft request' (duration: 102.481699ms)","trace[706629712] 'compare' (duration: 94.623556ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:59.158792Z","caller":"traceutil/trace.go:171","msg":"trace[7578527] linearizableReadLoop","detail":"{readStateIndex:5064; appliedIndex:5063; }","duration":"189.00526ms","start":"2026-07-14T17:40:58.969774Z","end":"2026-07-14T17:40:59.158779Z","steps":["trace[7578527] 'read index received' (duration: 93.178493ms)","trace[7578527] 'applied index is now lower than readState.Index' (duration: 95.825797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:59.15932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.360946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T17:40:59.15937Z","caller":"traceutil/trace.go:171","msg":"trace[1439956452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4879; }","duration":"186.425297ms","start":"2026-07-14T17:40:58.97293Z","end":"2026-07-14T17:40:59.159355Z","steps":["trace[1439956452] 'agreement among raft nodes before linearized reading' (duration: 186.307665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.159497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.726212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:40:59.159539Z","caller":"traceutil/trace.go:171","msg":"trace[250246154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4879; }","duration":"189.799574ms","start":"2026-07-14T17:40:58.969731Z","end":"2026-07-14T17:40:59.15953Z","steps":["trace[250246154] 'agreement among raft nodes before linearized reading' (duration: 189.091162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:59.159514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.984563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T17:40:59.159666Z","caller":"traceutil/trace.go:171","msg":"trace[1025420285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4879; }","duration":"107.101526ms","start":"2026-07-14T17:40:59.052493Z","end":"2026-07-14T17:40:59.159594Z","steps":["trace[1025420285] 'agreement among raft nodes before linearized reading' (duration: 106.914803ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:34.062158Z","caller":"traceutil/trace.go:171","msg":"trace[1275794993] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"105.933211ms","start":"2026-07-14T17:41:33.956202Z","end":"2026-07-14T17:41:34.062136Z","steps":["trace[1275794993] 'process raft request' (duration: 105.797919ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:28.558332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-07-14T17:42:28.645389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"85.607274ms","hash":2127318902,"current-db-size-bytes":53641216,"current-db-size":"54 MB","current-db-size-in-use-bytes":29507584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T17:42:28.645473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2127318902,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-07-14T17:42:33.961735Z","caller":"traceutil/trace.go:171","msg":"trace[183040953] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"105.094399ms","start":"2026-07-14T17:42:33.856614Z","end":"2026-07-14T17:42:33.961708Z","steps":["trace[183040953] 'process raft request' (duration: 104.989058ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:33.961766Z","caller":"traceutil/trace.go:171","msg":"trace[468729423] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"104.955408ms","start":"2026-07-14T17:42:33.856781Z","end":"2026-07-14T17:42:33.961737Z","steps":["trace[468729423] 'process raft request' (duration: 104.609543ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:33.96203Z","caller":"traceutil/trace.go:171","msg":"trace[1577273465] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"104.636354ms","start":"2026-07-14T17:42:33.85737Z","end":"2026-07-14T17:42:33.962006Z","steps":["trace[1577273465] 'process raft request' (duration: 104.289768ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:28.56375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5624} {"level":"info","ts":"2026-07-14T17:47:28.64803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5624,"took":"83.143836ms","hash":3715384891,"current-db-size-bytes":53641216,"current-db-size":"54 MB","current-db-size-in-use-bytes":18329600,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-14T17:47:28.648268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3715384891,"revision":5624,"compact-revision":2909}