{"level":"warn","ts":"2026-09-10T10:58:34.115823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T10:58:34.115926Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.68: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://162.253.55.68:2380","--initial-cluster=instance=https://162.253.55.68:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.68:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.68: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-09-10T10:58:34.11779Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T10:58:34.117818Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.68:2380"]} {"level":"info","ts":"2026-09-10T10:58:34.117841Z","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-09-10T10:58:34.119112Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"]} {"level":"info","ts":"2026-09-10T10:58:34.119347Z","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://162.253.55.68:2380"],"listen-peer-urls":["https://162.253.55.68:2380"],"advertise-client-urls":["https://162.253.55.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.68: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-09-10T10:58:34.125487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.810014ms"} {"level":"info","ts":"2026-09-10T10:58:34.132003Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f84e77da319eb1ed","cluster-id":"fd76717a29435cbd"} {"level":"info","ts":"2026-09-10T10:58:34.13212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T10:58:34.132145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became follower at term 0"} {"level":"info","ts":"2026-09-10T10:58:34.132158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f84e77da319eb1ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T10:58:34.13217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became follower at term 1"} {"level":"info","ts":"2026-09-10T10:58:34.132211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=(17892370148608618989)"} {"level":"warn","ts":"2026-09-10T10:58:34.142574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T10:58:34.144974Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T10:58:34.150441Z","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-09-10T10:58:34.152888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f84e77da319eb1ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T10:58:34.153134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f84e77da319eb1ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T10:58:34.154536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=(17892370148608618989)"} {"level":"info","ts":"2026-09-10T10:58:34.154774Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fd76717a29435cbd","local-member-id":"f84e77da319eb1ed","added-peer-id":"f84e77da319eb1ed","added-peer-peer-urls":["https://162.253.55.68:2380"]} {"level":"info","ts":"2026-09-10T10:58:34.153553Z","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-09-10T10:58:34.155345Z","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-09-10T10:58:34.160073Z","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-09-10T10:58:34.161634Z","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-09-10T10:58:34.161913Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f84e77da319eb1ed","initial-advertise-peer-urls":["https://162.253.55.68:2380"],"listen-peer-urls":["https://162.253.55.68:2380"],"advertise-client-urls":["https://162.253.55.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T10:58:34.161951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T10:58:34.162151Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.68:2380"} {"level":"info","ts":"2026-09-10T10:58:34.16217Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.68:2380"} {"level":"info","ts":"2026-09-10T10:58:35.133368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T10:58:35.133439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T10:58:35.133476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed received MsgPreVoteResp from f84e77da319eb1ed at term 1"} {"level":"info","ts":"2026-09-10T10:58:35.133496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became candidate at term 2"} {"level":"info","ts":"2026-09-10T10:58:35.133506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed received MsgVoteResp from f84e77da319eb1ed at term 2"} {"level":"info","ts":"2026-09-10T10:58:35.13353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became leader at term 2"} {"level":"info","ts":"2026-09-10T10:58:35.133542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f84e77da319eb1ed elected leader f84e77da319eb1ed at term 2"} {"level":"info","ts":"2026-09-10T10:58:35.13466Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f84e77da319eb1ed","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.68:2379]}","request-path":"/0/members/f84e77da319eb1ed/attributes","cluster-id":"fd76717a29435cbd","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T10:58:35.134677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:58:35.134658Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:35.134665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:58:35.13489Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T10:58:35.134917Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T10:58:35.135741Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fd76717a29435cbd","local-member-id":"f84e77da319eb1ed","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:35.135807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:35.135825Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:35.136248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T10:58:35.138621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.68:2379"} {"level":"info","ts":"2026-09-10T10:58:36.584969Z","caller":"traceutil/trace.go:171","msg":"trace[308014618] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"121.586559ms","start":"2026-09-10T10:58:36.463359Z","end":"2026-09-10T10:58:36.584946Z","steps":["trace[308014618] 'read index received' (duration: 121.366443ms)","trace[308014618] 'applied index is now lower than readState.Index' (duration: 219.396µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:58:36.585038Z","caller":"traceutil/trace.go:171","msg":"trace[1923452260] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"121.793624ms","start":"2026-09-10T10:58:36.4632Z","end":"2026-09-10T10:58:36.584994Z","steps":["trace[1923452260] 'process raft request' (duration: 121.555728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:36.585262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.872066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T10:58:36.58533Z","caller":"traceutil/trace.go:171","msg":"trace[1619169847] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"121.99021ms","start":"2026-09-10T10:58:36.463326Z","end":"2026-09-10T10:58:36.585316Z","steps":["trace[1619169847] 'agreement among raft nodes before linearized reading' (duration: 121.749183ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.002574Z","caller":"traceutil/trace.go:171","msg":"trace[771970442] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"481.894808ms","start":"2026-09-10T10:58:36.520656Z","end":"2026-09-10T10:58:37.00255Z","steps":["trace[771970442] 'process raft request' (duration: 481.664952ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.002694Z","caller":"traceutil/trace.go:171","msg":"trace[517126556] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"480.643475ms","start":"2026-09-10T10:58:36.522014Z","end":"2026-09-10T10:58:37.002657Z","steps":["trace[517126556] 'process raft request' (duration: 480.342897ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.002778Z","caller":"traceutil/trace.go:171","msg":"trace[1711547232] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"459.928317ms","start":"2026-09-10T10:58:36.542839Z","end":"2026-09-10T10:58:37.002767Z","steps":["trace[1711547232] 'process raft request' (duration: 459.758973ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.002839Z","caller":"traceutil/trace.go:171","msg":"trace[1655997455] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"460.03ms","start":"2026-09-10T10:58:36.542799Z","end":"2026-09-10T10:58:37.002829Z","steps":["trace[1655997455] 'process raft request' (duration: 459.713412ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.002852Z","caller":"traceutil/trace.go:171","msg":"trace[2043943188] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"539.189049ms","start":"2026-09-10T10:58:36.463474Z","end":"2026-09-10T10:58:37.002663Z","steps":["trace[2043943188] 'process raft request' (duration: 538.722877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.002885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.520613Z","time spent":"482.009141ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:58:37.002913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.542777Z","time spent":"460.085912ms","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:58:37.002936Z","caller":"traceutil/trace.go:171","msg":"trace[602221769] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"417.864288ms","start":"2026-09-10T10:58:36.585043Z","end":"2026-09-10T10:58:37.002907Z","steps":["trace[602221769] 'read index received' (duration: 417.085708ms)","trace[602221769] 'applied index is now lower than readState.Index' (duration: 777.55µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:58:37.003022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.522002Z","time spent":"480.741848ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:58:37.003162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-10T10:58:37.003159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.838536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T10:58:37.003195Z","caller":"traceutil/trace.go:171","msg":"trace[1171032184] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"482.049901ms","start":"2026-09-10T10:58:36.521136Z","end":"2026-09-10T10:58:37.003186Z","steps":["trace[1171032184] 'agreement among raft nodes before linearized reading' (duration: 481.97945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.00319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.868577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-10T10:58:37.003213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.521128Z","time spent":"482.080052ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-10T10:58:37.003209Z","caller":"traceutil/trace.go:171","msg":"trace[587880158] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"481.947939ms","start":"2026-09-10T10:58:36.521246Z","end":"2026-09-10T10:58:37.003194Z","steps":["trace[587880158] 'agreement among raft nodes before linearized reading' (duration: 481.783354ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.003241Z","caller":"traceutil/trace.go:171","msg":"trace[256965838] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"481.918488ms","start":"2026-09-10T10:58:36.521304Z","end":"2026-09-10T10:58:37.003223Z","steps":["trace[256965838] 'agreement among raft nodes before linearized reading' (duration: 481.700883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.521241Z","time spent":"481.99755ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-10T10:58:37.00329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.521272Z","time spent":"482.003171ms","remote":"127.0.0.1:42816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-09-10T10:58:37.003178Z","caller":"traceutil/trace.go:171","msg":"trace[1488087233] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"460.41619ms","start":"2026-09-10T10:58:36.542721Z","end":"2026-09-10T10:58:37.003137Z","steps":["trace[1488087233] 'process raft request' (duration: 459.665971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.542808Z","time spent":"459.993269ms","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:58:37.003289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.366449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.68\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T10:58:37.003418Z","caller":"traceutil/trace.go:171","msg":"trace[1783506592] range","detail":"{range_begin:/registry/masterleases/162.253.55.68; range_end:; response_count:0; response_revision:11; }","duration":"485.568814ms","start":"2026-09-10T10:58:36.51782Z","end":"2026-09-10T10:58:37.003389Z","steps":["trace[1783506592] 'agreement among raft nodes before linearized reading' (duration: 485.255606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.542698Z","time spent":"460.635116ms","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:58:37.003457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.378673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-10T10:58:37.003465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.51779Z","time spent":"485.666416ms","remote":"127.0.0.1:42760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.68\" "} {"level":"warn","ts":"2026-09-10T10:58:37.003465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.153581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T10:58:37.003492Z","caller":"traceutil/trace.go:171","msg":"trace[1453242965] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"460.593835ms","start":"2026-09-10T10:58:36.542882Z","end":"2026-09-10T10:58:37.003476Z","steps":["trace[1453242965] 'process raft request' (duration: 459.808674ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.0035Z","caller":"traceutil/trace.go:171","msg":"trace[929473609] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"484.442815ms","start":"2026-09-10T10:58:36.519042Z","end":"2026-09-10T10:58:37.003485Z","steps":["trace[929473609] 'agreement among raft nodes before linearized reading' (duration: 484.379844ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.003511Z","caller":"traceutil/trace.go:171","msg":"trace[1437782437] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"505.200933ms","start":"2026-09-10T10:58:36.498297Z","end":"2026-09-10T10:58:37.003498Z","steps":["trace[1437782437] 'agreement among raft nodes before linearized reading' (duration: 505.132421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.519024Z","time spent":"484.506796ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-10T10:58:37.003552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.498278Z","time spent":"505.262484ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-10T10:58:37.003565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.542848Z","time spent":"460.678757ms","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:58:37.003565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.018172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T10:58:37.003618Z","caller":"traceutil/trace.go:171","msg":"trace[284930208] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"404.094694ms","start":"2026-09-10T10:58:36.599507Z","end":"2026-09-10T10:58:37.003602Z","steps":["trace[284930208] 'agreement among raft nodes before linearized reading' (duration: 404.016342ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.003646Z","caller":"traceutil/trace.go:171","msg":"trace[1641512621] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"460.40164ms","start":"2026-09-10T10:58:36.543231Z","end":"2026-09-10T10:58:37.003633Z","steps":["trace[1641512621] 'process raft request' (duration: 459.525537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.599491Z","time spent":"404.153275ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-10T10:58:37.003686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.478863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-10T10:58:37.0032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.463459Z","time spent":"539.456136ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T10:58:37.003718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.543212Z","time spent":"460.464771ms","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:58:37.003726Z","caller":"traceutil/trace.go:171","msg":"trace[1734697061] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"432.557675ms","start":"2026-09-10T10:58:36.571156Z","end":"2026-09-10T10:58:37.003714Z","steps":["trace[1734697061] 'agreement among raft nodes before linearized reading' (duration: 432.499484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.571096Z","time spent":"432.657227ms","remote":"127.0.0.1:42840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-10T10:58:37.003809Z","caller":"traceutil/trace.go:171","msg":"trace[1985163037] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"460.705408ms","start":"2026-09-10T10:58:36.543088Z","end":"2026-09-10T10:58:37.003794Z","steps":["trace[1985163037] 'process raft request' (duration: 459.63995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.003887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:36.543081Z","time spent":"460.76291ms","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:58:37.176057Z","caller":"traceutil/trace.go:171","msg":"trace[158989271] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"166.76244ms","start":"2026-09-10T10:58:37.009273Z","end":"2026-09-10T10:58:37.176035Z","steps":["trace[158989271] 'process raft request' (duration: 164.965732ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.177847Z","caller":"traceutil/trace.go:171","msg":"trace[668161944] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"108.665156ms","start":"2026-09-10T10:58:37.069161Z","end":"2026-09-10T10:58:37.177826Z","steps":["trace[668161944] 'read index received' (duration: 105.107722ms)","trace[668161944] 'applied index is now lower than readState.Index' (duration: 3.556604ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:58:37.177852Z","caller":"traceutil/trace.go:171","msg":"trace[508546687] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"166.549725ms","start":"2026-09-10T10:58:37.01125Z","end":"2026-09-10T10:58:37.177799Z","steps":["trace[508546687] 'process raft request' (duration: 166.3908ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.17796Z","caller":"traceutil/trace.go:171","msg":"trace[1795410101] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"165.535558ms","start":"2026-09-10T10:58:37.012417Z","end":"2026-09-10T10:58:37.177953Z","steps":["trace[1795410101] 'process raft request' (duration: 165.26271ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178099Z","caller":"traceutil/trace.go:171","msg":"trace[1042220506] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"165.483925ms","start":"2026-09-10T10:58:37.012609Z","end":"2026-09-10T10:58:37.178093Z","steps":["trace[1042220506] 'process raft request' (duration: 165.087074ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178209Z","caller":"traceutil/trace.go:171","msg":"trace[1410867240] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"168.838865ms","start":"2026-09-10T10:58:37.009351Z","end":"2026-09-10T10:58:37.17819Z","steps":["trace[1410867240] 'process raft request' (duration: 168.116605ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178303Z","caller":"traceutil/trace.go:171","msg":"trace[648946744] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"158.816709ms","start":"2026-09-10T10:58:37.019476Z","end":"2026-09-10T10:58:37.178293Z","steps":["trace[648946744] 'process raft request' (duration: 158.313366ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178294Z","caller":"traceutil/trace.go:171","msg":"trace[1095555272] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"168.184647ms","start":"2026-09-10T10:58:37.010079Z","end":"2026-09-10T10:58:37.178264Z","steps":["trace[1095555272] 'process raft request' (duration: 167.535829ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178355Z","caller":"traceutil/trace.go:171","msg":"trace[426012220] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"165.695751ms","start":"2026-09-10T10:58:37.012636Z","end":"2026-09-10T10:58:37.178331Z","steps":["trace[426012220] 'process raft request' (duration: 165.076034ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.17829Z","caller":"traceutil/trace.go:171","msg":"trace[1002502107] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"165.481015ms","start":"2026-09-10T10:58:37.012723Z","end":"2026-09-10T10:58:37.178204Z","steps":["trace[1002502107] 'process raft request' (duration: 165.019292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:37.178459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.276102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-10T10:58:37.178537Z","caller":"traceutil/trace.go:171","msg":"trace[1841340452] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"109.428376ms","start":"2026-09-10T10:58:37.069095Z","end":"2026-09-10T10:58:37.178523Z","steps":["trace[1841340452] 'agreement among raft nodes before linearized reading' (duration: 109.302013ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:37.178709Z","caller":"traceutil/trace.go:171","msg":"trace[1190393744] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"164.662494ms","start":"2026-09-10T10:58:37.014028Z","end":"2026-09-10T10:58:37.178691Z","steps":["trace[1190393744] 'process raft request' (duration: 163.73271ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:27.128098Z","caller":"traceutil/trace.go:171","msg":"trace[540335370] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"261.663765ms","start":"2026-09-10T10:59:26.866379Z","end":"2026-09-10T10:59:27.128042Z","steps":["trace[540335370] 'process raft request' (duration: 256.933666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:33.918593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.327736ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-10T10:59:33.918735Z","caller":"traceutil/trace.go:171","msg":"trace[1202817820] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"161.246365ms","start":"2026-09-10T10:59:33.757467Z","end":"2026-09-10T10:59:33.918713Z","steps":["trace[1202817820] 'read index received' (duration: 67.971µs)","trace[1202817820] 'applied index is now lower than readState.Index' (duration: 161.176744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:59:33.918866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.384237ms","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-09-10T10:59:33.918916Z","caller":"traceutil/trace.go:171","msg":"trace[130722024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"161.46669ms","start":"2026-09-10T10:59:33.757436Z","end":"2026-09-10T10:59:33.918902Z","steps":["trace[130722024] 'agreement among raft nodes before linearized reading' (duration: 161.334617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:34.717439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.346134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-10T10:59:34.717596Z","caller":"traceutil/trace.go:171","msg":"trace[1132731715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"517.565178ms","start":"2026-09-10T10:59:34.199998Z","end":"2026-09-10T10:59:34.717563Z","steps":["trace[1132731715] 'range keys from in-memory index tree' (duration: 517.239323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:34.717584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.248796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-596n2\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-10T10:59:34.717637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.098858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-10T10:59:34.717649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:34.199978Z","time spent":"517.660919ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-10T10:59:34.71766Z","caller":"traceutil/trace.go:171","msg":"trace[237664578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-596n2; range_end:; response_count:1; response_revision:684; }","duration":"514.354598ms","start":"2026-09-10T10:59:34.203286Z","end":"2026-09-10T10:59:34.71764Z","steps":["trace[237664578] 'range keys from in-memory index tree' (duration: 514.117834ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:34.717666Z","caller":"traceutil/trace.go:171","msg":"trace[1150854819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"603.200859ms","start":"2026-09-10T10:59:34.114456Z","end":"2026-09-10T10:59:34.717657Z","steps":["trace[1150854819] 'range keys from in-memory index tree' (duration: 603.031447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:34.717701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:34.203278Z","time spent":"514.416639ms","remote":"127.0.0.1:42900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-596n2\" "} {"level":"warn","ts":"2026-09-10T10:59:34.717701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:34.114438Z","time spent":"603.256671ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-10T10:59:34.848082Z","caller":"traceutil/trace.go:171","msg":"trace[1133258378] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"106.479518ms","start":"2026-09-10T10:59:34.741573Z","end":"2026-09-10T10:59:34.848052Z","steps":["trace[1133258378] 'process raft request' (duration: 106.389067ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:34.84807Z","caller":"traceutil/trace.go:171","msg":"trace[173617138] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"123.90003ms","start":"2026-09-10T10:59:34.724142Z","end":"2026-09-10T10:59:34.848042Z","steps":["trace[173617138] 'process raft request' (duration: 109.448994ms)","trace[173617138] 'compare' (duration: 14.105731ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:52.19161Z","caller":"traceutil/trace.go:171","msg":"trace[385199683] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"157.843274ms","start":"2026-09-10T10:59:52.033742Z","end":"2026-09-10T10:59:52.191585Z","steps":["trace[385199683] 'read index received' (duration: 157.718812ms)","trace[385199683] 'applied index is now lower than readState.Index' (duration: 123.682µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:52.191726Z","caller":"traceutil/trace.go:171","msg":"trace[578955600] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"311.9118ms","start":"2026-09-10T10:59:51.879802Z","end":"2026-09-10T10:59:52.191714Z","steps":["trace[578955600] 'process raft request' (duration: 311.633655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:52.191837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:51.879784Z","time spent":"311.974121ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T10:59:52.191948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.961371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T10:59:52.192016Z","caller":"traceutil/trace.go:171","msg":"trace[1588804792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"118.064743ms","start":"2026-09-10T10:59:52.073934Z","end":"2026-09-10T10:59:52.191999Z","steps":["trace[1588804792] 'agreement among raft nodes before linearized reading' (duration: 117.969591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:52.192283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.538576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-10T10:59:52.192346Z","caller":"traceutil/trace.go:171","msg":"trace[153563581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"158.642919ms","start":"2026-09-10T10:59:52.033685Z","end":"2026-09-10T10:59:52.192328Z","steps":["trace[153563581] 'agreement among raft nodes before linearized reading' (duration: 158.468326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:52.546433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.326717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T10:59:52.54669Z","caller":"traceutil/trace.go:171","msg":"trace[1377561511] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"349.005046ms","start":"2026-09-10T10:59:52.197653Z","end":"2026-09-10T10:59:52.546658Z","steps":["trace[1377561511] 'process raft request' (duration: 238.189341ms)","trace[1377561511] 'compare' (duration: 110.002571ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:59:52.546836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:52.197627Z","time spent":"349.159779ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T10:59:52.571206Z","caller":"traceutil/trace.go:171","msg":"trace[2040672928] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"212.299735ms","start":"2026-09-10T10:59:52.358876Z","end":"2026-09-10T10:59:52.571176Z","steps":["trace[2040672928] 'process raft request' (duration: 212.150772ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:41.712057Z","caller":"traceutil/trace.go:171","msg":"trace[336341015] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"116.83524ms","start":"2026-09-10T11:00:41.595205Z","end":"2026-09-10T11:00:41.71204Z","steps":["trace[336341015] 'process raft request' (duration: 116.700647ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:41.740674Z","caller":"traceutil/trace.go:171","msg":"trace[352090959] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"139.216667ms","start":"2026-09-10T11:00:41.601449Z","end":"2026-09-10T11:00:41.740666Z","steps":["trace[352090959] 'process raft request' (duration: 139.152916ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:41.740699Z","caller":"traceutil/trace.go:171","msg":"trace[359359914] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"140.574535ms","start":"2026-09-10T11:00:41.600105Z","end":"2026-09-10T11:00:41.74068Z","steps":["trace[359359914] 'process raft request' (duration: 140.407922ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:45.904562Z","caller":"traceutil/trace.go:171","msg":"trace[1900883068] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"158.435704ms","start":"2026-09-10T11:00:45.746095Z","end":"2026-09-10T11:00:45.904531Z","steps":["trace[1900883068] 'read index received' (duration: 158.25015ms)","trace[1900883068] 'applied index is now lower than readState.Index' (duration: 184.374µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:45.904631Z","caller":"traceutil/trace.go:171","msg":"trace[1475544024] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"213.28661ms","start":"2026-09-10T11:00:45.691307Z","end":"2026-09-10T11:00:45.904594Z","steps":["trace[1475544024] 'process raft request' (duration: 212.996474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:45.904803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.663499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:00:45.904865Z","caller":"traceutil/trace.go:171","msg":"trace[1103179751] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1468; }","duration":"158.799512ms","start":"2026-09-10T11:00:45.746051Z","end":"2026-09-10T11:00:45.90485Z","steps":["trace[1103179751] 'agreement among raft nodes before linearized reading' (duration: 158.648769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:45.904893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.734727ms","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-09-10T11:00:45.904921Z","caller":"traceutil/trace.go:171","msg":"trace[2073739819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1468; }","duration":"135.800608ms","start":"2026-09-10T11:00:45.769111Z","end":"2026-09-10T11:00:45.904912Z","steps":["trace[2073739819] 'agreement among raft nodes before linearized reading' (duration: 135.607974ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:56.272969Z","caller":"traceutil/trace.go:171","msg":"trace[2045075198] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"136.460719ms","start":"2026-09-10T11:00:56.136476Z","end":"2026-09-10T11:00:56.272936Z","steps":["trace[2045075198] 'process raft request' (duration: 136.205753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:11.132332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.40642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6d81bb37-63b5-4d86-b1b5-fd59060ef7a4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-10T11:01:11.132398Z","caller":"traceutil/trace.go:171","msg":"trace[1508692644] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6d81bb37-63b5-4d86-b1b5-fd59060ef7a4; range_end:; response_count:1; response_revision:1871; }","duration":"398.529373ms","start":"2026-09-10T11:01:10.733855Z","end":"2026-09-10T11:01:11.132385Z","steps":["trace[1508692644] 'range keys from in-memory index tree' (duration: 398.265507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:11.132421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:10.733788Z","time spent":"398.627964ms","remote":"127.0.0.1:42900","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-6d81bb37-63b5-4d86-b1b5-fd59060ef7a4\" "} {"level":"warn","ts":"2026-09-10T11:01:11.132604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.340499ms","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":"info","ts":"2026-09-10T11:01:11.13262Z","caller":"traceutil/trace.go:171","msg":"trace[625383350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"378.415101ms","start":"2026-09-10T11:01:10.7542Z","end":"2026-09-10T11:01:11.132615Z","steps":["trace[625383350] 'range keys from in-memory index tree' (duration: 378.242677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:11.132633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:10.754179Z","time spent":"378.449822ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-10T11:01:11.133132Z","caller":"traceutil/trace.go:171","msg":"trace[191077842] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"294.878581ms","start":"2026-09-10T11:01:10.838216Z","end":"2026-09-10T11:01:11.133095Z","steps":["trace[191077842] 'process raft request' (duration: 260.563539ms)","trace[191077842] 'compare' (duration: 33.884493ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:11.161985Z","caller":"traceutil/trace.go:171","msg":"trace[760675513] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"191.514878ms","start":"2026-09-10T11:01:10.97045Z","end":"2026-09-10T11:01:11.161964Z","steps":["trace[760675513] 'read index received' (duration: 128.341963ms)","trace[760675513] 'applied index is now lower than readState.Index' (duration: 63.172005ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:11.16211Z","caller":"traceutil/trace.go:171","msg":"trace[1142079677] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"294.716268ms","start":"2026-09-10T11:01:10.867357Z","end":"2026-09-10T11:01:11.162074Z","steps":["trace[1142079677] 'process raft request' (duration: 294.484303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:11.162184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.707992ms","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-09-10T11:01:11.162221Z","caller":"traceutil/trace.go:171","msg":"trace[308440501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1873; }","duration":"191.804944ms","start":"2026-09-10T11:01:10.970406Z","end":"2026-09-10T11:01:11.162211Z","steps":["trace[308440501] 'agreement among raft nodes before linearized reading' (duration: 191.658571ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:11.364471Z","caller":"traceutil/trace.go:171","msg":"trace[390122882] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1951; }","duration":"202.409499ms","start":"2026-09-10T11:01:11.162045Z","end":"2026-09-10T11:01:11.364455Z","steps":["trace[390122882] 'read index received' (duration: 175.5599ms)","trace[390122882] 'applied index is now lower than readState.Index' (duration: 26.847049ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:11.364517Z","caller":"traceutil/trace.go:171","msg":"trace[677288367] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"227.751725ms","start":"2026-09-10T11:01:11.136736Z","end":"2026-09-10T11:01:11.364488Z","steps":["trace[677288367] 'process raft request' (duration: 200.755332ms)","trace[677288367] 'compare' (duration: 26.797637ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:11.364687Z","caller":"traceutil/trace.go:171","msg":"trace[1066397671] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"225.006225ms","start":"2026-09-10T11:01:11.139671Z","end":"2026-09-10T11:01:11.364677Z","steps":["trace[1066397671] 'process raft request' (duration: 224.725579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:11.364749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.073376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6d81bb37-63b5-4d86-b1b5-fd59060ef7a4\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-10T11:01:11.364775Z","caller":"traceutil/trace.go:171","msg":"trace[122340733] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6d81bb37-63b5-4d86-b1b5-fd59060ef7a4; range_end:; response_count:1; response_revision:1875; }","duration":"282.146858ms","start":"2026-09-10T11:01:11.08262Z","end":"2026-09-10T11:01:11.364767Z","steps":["trace[122340733] 'agreement among raft nodes before linearized reading' (duration: 282.084846ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:11.378458Z","caller":"traceutil/trace.go:171","msg":"trace[1032005416] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"213.036733ms","start":"2026-09-10T11:01:11.165403Z","end":"2026-09-10T11:01:11.37844Z","steps":["trace[1032005416] 'process raft request' (duration: 212.944971ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:11.716111Z","caller":"traceutil/trace.go:171","msg":"trace[1854820735] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"248.113472ms","start":"2026-09-10T11:01:11.467939Z","end":"2026-09-10T11:01:11.716053Z","steps":["trace[1854820735] 'process raft request' (duration: 246.046546ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:11.716193Z","caller":"traceutil/trace.go:171","msg":"trace[1669182432] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"215.768281ms","start":"2026-09-10T11:01:11.500398Z","end":"2026-09-10T11:01:11.716167Z","steps":["trace[1669182432] 'read index received' (duration: 213.633734ms)","trace[1669182432] 'applied index is now lower than readState.Index' (duration: 2.132987ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:01:11.716484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.057518ms","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-09-10T11:01:11.716581Z","caller":"traceutil/trace.go:171","msg":"trace[1292772869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1877; }","duration":"216.205301ms","start":"2026-09-10T11:01:11.500351Z","end":"2026-09-10T11:01:11.716557Z","steps":["trace[1292772869] 'agreement among raft nodes before linearized reading' (duration: 215.915544ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:12.870693Z","caller":"traceutil/trace.go:171","msg":"trace[748907475] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"107.998301ms","start":"2026-09-10T11:01:12.762677Z","end":"2026-09-10T11:01:12.870675Z","steps":["trace[748907475] 'process raft request' (duration: 107.877958ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:12.893098Z","caller":"traceutil/trace.go:171","msg":"trace[1955386825] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"127.281734ms","start":"2026-09-10T11:01:12.76579Z","end":"2026-09-10T11:01:12.893072Z","steps":["trace[1955386825] 'process raft request' (duration: 127.12445ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:24.268194Z","caller":"traceutil/trace.go:171","msg":"trace[1055147795] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"124.170413ms","start":"2026-09-10T11:01:24.144Z","end":"2026-09-10T11:01:24.26817Z","steps":["trace[1055147795] 'process raft request' (duration: 124.03466ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:44.652359Z","caller":"traceutil/trace.go:171","msg":"trace[1394183601] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"134.358411ms","start":"2026-09-10T11:01:44.517974Z","end":"2026-09-10T11:01:44.652333Z","steps":["trace[1394183601] 'process raft request' (duration: 134.118696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:44.959647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.589616ms","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-09-10T11:01:44.95972Z","caller":"traceutil/trace.go:171","msg":"trace[1002902451] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2116; }","duration":"172.705009ms","start":"2026-09-10T11:01:44.786996Z","end":"2026-09-10T11:01:44.959701Z","steps":["trace[1002902451] 'range keys from in-memory index tree' (duration: 172.371602ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:45.445316Z","caller":"traceutil/trace.go:171","msg":"trace[461741180] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"122.997427ms","start":"2026-09-10T11:01:45.322296Z","end":"2026-09-10T11:01:45.445293Z","steps":["trace[461741180] 'process raft request' (duration: 122.836703ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:45.748965Z","caller":"traceutil/trace.go:171","msg":"trace[489839104] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"173.323175ms","start":"2026-09-10T11:01:45.575619Z","end":"2026-09-10T11:01:45.748942Z","steps":["trace[489839104] 'read index received' (duration: 173.06558ms)","trace[489839104] 'applied index is now lower than readState.Index' (duration: 256.695µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:01:45.749222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.589421ms","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-09-10T11:01:45.749648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.997124ms","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":"info","ts":"2026-09-10T11:01:45.749709Z","caller":"traceutil/trace.go:171","msg":"trace[45441857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2119; }","duration":"164.663869ms","start":"2026-09-10T11:01:45.585032Z","end":"2026-09-10T11:01:45.749696Z","steps":["trace[45441857] 'agreement among raft nodes before linearized reading' (duration: 164.284911ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:45.749683Z","caller":"traceutil/trace.go:171","msg":"trace[2022155989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2119; }","duration":"174.102623ms","start":"2026-09-10T11:01:45.575569Z","end":"2026-09-10T11:01:45.749672Z","steps":["trace[2022155989] 'agreement among raft nodes before linearized reading' (duration: 173.51983ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:45.749306Z","caller":"traceutil/trace.go:171","msg":"trace[6278605] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"349.062806ms","start":"2026-09-10T11:01:45.399946Z","end":"2026-09-10T11:01:45.749009Z","steps":["trace[6278605] 'process raft request' (duration: 348.766429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:45.749898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:45.399916Z","time spent":"349.885365ms","remote":"127.0.0.1:42808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T11:01:46.009237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.849685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-10T11:01:46.009236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.786689ms","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-09-10T11:01:46.009302Z","caller":"traceutil/trace.go:171","msg":"trace[1389430124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"138.956578ms","start":"2026-09-10T11:01:45.870334Z","end":"2026-09-10T11:01:46.00929Z","steps":["trace[1389430124] 'range keys from in-memory index tree' (duration: 138.722493ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.009331Z","caller":"traceutil/trace.go:171","msg":"trace[1389720777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2121; }","duration":"121.936052ms","start":"2026-09-10T11:01:45.887377Z","end":"2026-09-10T11:01:46.009313Z","steps":["trace[1389720777] 'range keys from in-memory index tree' (duration: 121.569974ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.175465Z","caller":"traceutil/trace.go:171","msg":"trace[1666599113] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"163.606728ms","start":"2026-09-10T11:01:46.011832Z","end":"2026-09-10T11:01:46.175438Z","steps":["trace[1666599113] 'process raft request' (duration: 163.435194ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.203867Z","caller":"traceutil/trace.go:171","msg":"trace[1529450194] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"191.392698ms","start":"2026-09-10T11:01:46.01245Z","end":"2026-09-10T11:01:46.203842Z","steps":["trace[1529450194] 'process raft request' (duration: 191.214534ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.20406Z","caller":"traceutil/trace.go:171","msg":"trace[713888731] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2208; }","duration":"131.93113ms","start":"2026-09-10T11:01:46.072099Z","end":"2026-09-10T11:01:46.204031Z","steps":["trace[713888731] 'read index received' (duration: 103.196809ms)","trace[713888731] 'applied index is now lower than readState.Index' (duration: 28.732481ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:46.204158Z","caller":"traceutil/trace.go:171","msg":"trace[1835882964] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"191.665054ms","start":"2026-09-10T11:01:46.012481Z","end":"2026-09-10T11:01:46.204146Z","steps":["trace[1835882964] 'process raft request' (duration: 191.316966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:46.204185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.065003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:01:46.204219Z","caller":"traceutil/trace.go:171","msg":"trace[1635473632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"132.115414ms","start":"2026-09-10T11:01:46.072092Z","end":"2026-09-10T11:01:46.204207Z","steps":["trace[1635473632] 'agreement among raft nodes before linearized reading' (duration: 132.033142ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.51013Z","caller":"traceutil/trace.go:171","msg":"trace[141312015] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"115.198971ms","start":"2026-09-10T11:01:46.394912Z","end":"2026-09-10T11:01:46.510111Z","steps":["trace[141312015] 'process raft request' (duration: 115.083949ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.732379Z","caller":"traceutil/trace.go:171","msg":"trace[385239978] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"141.817625ms","start":"2026-09-10T11:01:46.590528Z","end":"2026-09-10T11:01:46.732345Z","steps":["trace[385239978] 'process raft request' (duration: 125.417543ms)","trace[385239978] 'compare' (duration: 16.166236ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:46.865894Z","caller":"traceutil/trace.go:171","msg":"trace[1220898585] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2213; }","duration":"108.926538ms","start":"2026-09-10T11:01:46.756943Z","end":"2026-09-10T11:01:46.86587Z","steps":["trace[1220898585] 'read index received' (duration: 107.812824ms)","trace[1220898585] 'applied index is now lower than readState.Index' (duration: 1.112704ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:46.865936Z","caller":"traceutil/trace.go:171","msg":"trace[1601886797] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"208.528185ms","start":"2026-09-10T11:01:46.65739Z","end":"2026-09-10T11:01:46.865918Z","steps":["trace[1601886797] 'process raft request' (duration: 207.355239ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.866171Z","caller":"traceutil/trace.go:171","msg":"trace[737432494] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"184.407968ms","start":"2026-09-10T11:01:46.681687Z","end":"2026-09-10T11:01:46.866095Z","steps":["trace[737432494] 'process raft request' (duration: 184.113482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:46.866592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.633665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-10T11:01:46.866659Z","caller":"traceutil/trace.go:171","msg":"trace[1121649728] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"109.744268ms","start":"2026-09-10T11:01:46.756897Z","end":"2026-09-10T11:01:46.866642Z","steps":["trace[1121649728] 'agreement among raft nodes before linearized reading' (duration: 109.332898ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:42.36852Z","caller":"traceutil/trace.go:171","msg":"trace[443275545] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"182.839241ms","start":"2026-09-10T11:02:42.185657Z","end":"2026-09-10T11:02:42.368496Z","steps":["trace[443275545] 'process raft request' (duration: 182.272418ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:18.637043Z","caller":"traceutil/trace.go:171","msg":"trace[787927225] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"244.781863ms","start":"2026-09-10T11:03:18.391879Z","end":"2026-09-10T11:03:18.636661Z","steps":["trace[787927225] 'process raft request' (duration: 244.62555ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:18.753695Z","caller":"traceutil/trace.go:171","msg":"trace[756400598] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"360.084358ms","start":"2026-09-10T11:03:18.393586Z","end":"2026-09-10T11:03:18.75367Z","steps":["trace[756400598] 'process raft request' (duration: 306.749197ms)","trace[756400598] 'compare' (duration: 53.111876ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:18.75416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:18.393571Z","time spent":"360.497178ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:03:29.248494Z","caller":"traceutil/trace.go:171","msg":"trace[787574308] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"156.000756ms","start":"2026-09-10T11:03:29.09247Z","end":"2026-09-10T11:03:29.248471Z","steps":["trace[787574308] 'process raft request' (duration: 155.870464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:29.685617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.846201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:29.685713Z","caller":"traceutil/trace.go:171","msg":"trace[64831776] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2786; }","duration":"170.974434ms","start":"2026-09-10T11:03:29.514714Z","end":"2026-09-10T11:03:29.685688Z","steps":["trace[64831776] 'count revisions from in-memory index tree' (duration: 170.77853ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:29.824923Z","caller":"traceutil/trace.go:171","msg":"trace[1116601222] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"134.836677ms","start":"2026-09-10T11:03:29.690044Z","end":"2026-09-10T11:03:29.824881Z","steps":["trace[1116601222] 'process raft request' (duration: 134.551871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:48.074449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.115333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:499068"} {"level":"info","ts":"2026-09-10T11:07:48.074558Z","caller":"traceutil/trace.go:171","msg":"trace[1944394353] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4757; }","duration":"121.244886ms","start":"2026-09-10T11:07:47.9533Z","end":"2026-09-10T11:07:48.074545Z","steps":["trace[1944394353] 'range keys from in-memory index tree' (duration: 120.799646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:48.075039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.224689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-10T11:07:48.075126Z","caller":"traceutil/trace.go:171","msg":"trace[45673788] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4757; }","duration":"128.323531ms","start":"2026-09-10T11:07:47.946785Z","end":"2026-09-10T11:07:48.075108Z","steps":["trace[45673788] 'range keys from in-memory index tree' (duration: 127.309448ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:51.888974Z","caller":"traceutil/trace.go:171","msg":"trace[1172018087] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"114.829337ms","start":"2026-09-10T11:07:51.774109Z","end":"2026-09-10T11:07:51.888938Z","steps":["trace[1172018087] 'process raft request' (duration: 114.674074ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:52.066618Z","caller":"traceutil/trace.go:171","msg":"trace[1942812680] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"228.930955ms","start":"2026-09-10T11:07:51.837661Z","end":"2026-09-10T11:07:52.066592Z","steps":["trace[1942812680] 'process raft request' (duration: 228.887534ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:52.066714Z","caller":"traceutil/trace.go:171","msg":"trace[826029555] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5052; }","duration":"120.11262ms","start":"2026-09-10T11:07:51.946582Z","end":"2026-09-10T11:07:52.066694Z","steps":["trace[826029555] 'read index received' (duration: 120.10466ms)","trace[826029555] 'applied index is now lower than readState.Index' (duration: 6.13µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:52.066777Z","caller":"traceutil/trace.go:171","msg":"trace[1675505096] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"267.553119ms","start":"2026-09-10T11:07:51.799202Z","end":"2026-09-10T11:07:52.066755Z","steps":["trace[1675505096] 'process raft request' (duration: 267.225282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:52.066875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.273344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:07:52.066915Z","caller":"traceutil/trace.go:171","msg":"trace[20215007] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4859; }","duration":"120.371997ms","start":"2026-09-10T11:07:51.946531Z","end":"2026-09-10T11:07:52.066903Z","steps":["trace[20215007] 'agreement among raft nodes before linearized reading' (duration: 120.252734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:52.068044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.247833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-10T11:07:52.068075Z","caller":"traceutil/trace.go:171","msg":"trace[188104227] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4859; }","duration":"102.316475ms","start":"2026-09-10T11:07:51.965751Z","end":"2026-09-10T11:07:52.068067Z","steps":["trace[188104227] 'agreement among raft nodes before linearized reading' (duration: 101.112276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:58.642901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.925024ms","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-09-10T11:07:58.642901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.706586ms","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-09-10T11:07:58.642964Z","caller":"traceutil/trace.go:171","msg":"trace[1597786234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4970; }","duration":"168.997126ms","start":"2026-09-10T11:07:58.473953Z","end":"2026-09-10T11:07:58.64295Z","steps":["trace[1597786234] 'range keys from in-memory index tree' (duration: 168.78504ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:58.642977Z","caller":"traceutil/trace.go:171","msg":"trace[849450494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4970; }","duration":"182.825839ms","start":"2026-09-10T11:07:58.460134Z","end":"2026-09-10T11:07:58.64296Z","steps":["trace[849450494] 'range keys from in-memory index tree' (duration: 182.541832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:58.642989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.231786ms","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-09-10T11:07:58.643101Z","caller":"traceutil/trace.go:171","msg":"trace[1223016434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4970; }","duration":"156.36924ms","start":"2026-09-10T11:07:58.486719Z","end":"2026-09-10T11:07:58.643089Z","steps":["trace[1223016434] 'range keys from in-memory index tree' (duration: 156.137125ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:09.992174Z","caller":"traceutil/trace.go:171","msg":"trace[2066145815] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5323; }","duration":"172.1358ms","start":"2026-09-10T11:08:09.820012Z","end":"2026-09-10T11:08:09.992148Z","steps":["trace[2066145815] 'read index received' (duration: 171.090866ms)","trace[2066145815] 'applied index is now lower than readState.Index' (duration: 1.043724ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:09.992249Z","caller":"traceutil/trace.go:171","msg":"trace[1221458395] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"220.536003ms","start":"2026-09-10T11:08:09.771685Z","end":"2026-09-10T11:08:09.992221Z","steps":["trace[1221458395] 'process raft request' (duration: 219.491319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:09.993769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.718567ms","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-09-10T11:08:09.993878Z","caller":"traceutil/trace.go:171","msg":"trace[529492109] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5124; }","duration":"173.883671ms","start":"2026-09-10T11:08:09.819972Z","end":"2026-09-10T11:08:09.993856Z","steps":["trace[529492109] 'agreement among raft nodes before linearized reading' (duration: 172.455467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:10.439647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.935017ms","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-09-10T11:08:10.439745Z","caller":"traceutil/trace.go:171","msg":"trace[1963726525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5128; }","duration":"190.125921ms","start":"2026-09-10T11:08:10.249598Z","end":"2026-09-10T11:08:10.439724Z","steps":["trace[1963726525] 'range keys from in-memory index tree' (duration: 189.63126ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:35.163066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2816} {"level":"info","ts":"2026-09-10T11:08:35.238694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2816,"took":"74.245809ms","hash":1897918550,"current-db-size-bytes":51462144,"current-db-size":"52 MB","current-db-size-in-use-bytes":27381760,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-10T11:08:35.238748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897918550,"revision":2816,"compact-revision":-1} {"level":"info","ts":"2026-09-10T11:08:51.241905Z","caller":"traceutil/trace.go:171","msg":"trace[559239391] linearizableReadLoop","detail":"{readStateIndex:5702; appliedIndex:5701; }","duration":"224.877092ms","start":"2026-09-10T11:08:51.016991Z","end":"2026-09-10T11:08:51.241869Z","steps":["trace[559239391] 'read index received' (duration: 87.626224ms)","trace[559239391] 'applied index is now lower than readState.Index' (duration: 137.249398ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:51.242082Z","caller":"traceutil/trace.go:171","msg":"trace[1865474252] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"230.732318ms","start":"2026-09-10T11:08:51.011239Z","end":"2026-09-10T11:08:51.241971Z","steps":["trace[1865474252] 'process raft request' (duration: 183.44575ms)","trace[1865474252] 'compare' (duration: 47.049813ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:08:51.242329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.312272ms","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":"info","ts":"2026-09-10T11:08:51.242391Z","caller":"traceutil/trace.go:171","msg":"trace[1328713562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5492; }","duration":"225.424675ms","start":"2026-09-10T11:08:51.016951Z","end":"2026-09-10T11:08:51.242376Z","steps":["trace[1328713562] 'agreement among raft nodes before linearized reading' (duration: 225.100107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:51.242458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.911351ms","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-09-10T11:08:51.242577Z","caller":"traceutil/trace.go:171","msg":"trace[779011635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5492; }","duration":"120.106186ms","start":"2026-09-10T11:08:51.122454Z","end":"2026-09-10T11:08:51.24256Z","steps":["trace[779011635] 'agreement among raft nodes before linearized reading' (duration: 119.891751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:51.242762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.663915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-10T11:08:51.242816Z","caller":"traceutil/trace.go:171","msg":"trace[218040947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5492; }","duration":"119.745106ms","start":"2026-09-10T11:08:51.123057Z","end":"2026-09-10T11:08:51.242802Z","steps":["trace[218040947] 'agreement among raft nodes before linearized reading' (duration: 119.593463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:51.242791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.623103ms","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-09-10T11:08:51.242909Z","caller":"traceutil/trace.go:171","msg":"trace[941696724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5492; }","duration":"119.777247ms","start":"2026-09-10T11:08:51.123111Z","end":"2026-09-10T11:08:51.242888Z","steps":["trace[941696724] 'agreement among raft nodes before linearized reading' (duration: 119.434429ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:13:35.167978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5311} {"level":"info","ts":"2026-09-10T11:13:35.24936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5311,"took":"80.36125ms","hash":709422012,"current-db-size-bytes":51462144,"current-db-size":"52 MB","current-db-size-in-use-bytes":18706432,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T11:13:35.249415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":709422012,"revision":5311,"compact-revision":2816}