{"level":"warn","ts":"2026-05-11T17:54:45.388492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T17:54:45.388591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05-11T17:54:45.388663Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T17:54:45.388674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T17:54:45.388692Z","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-05-11T17:54:45.389339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-11T17:54:45.389448Z","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.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05-11T17:54:45.393818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.170919ms"} {"level":"info","ts":"2026-05-11T17:54:45.408827Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-11T17:54:45.408895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T17:54:45.408923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-11T17:54:45.408937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T17:54:45.408953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-11T17:54:45.408991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-11T17:54:45.426262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T17:54:45.427786Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T17:54:45.428771Z","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-05-11T17:54:45.4312Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T17:54:45.433368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T17:54:45.433423Z","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-05-11T17:54:45.433575Z","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-05-11T17:54:45.43361Z","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-05-11T17:54:45.434069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-11T17:54:45.434214Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-11T17:54:45.43549Z","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-05-11T17:54:45.435557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T17:54:45.435644Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-11T17:54:45.436449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T17:54:45.436481Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T17:54:46.010039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T17:54:46.010085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T17:54:46.010118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-11T17:54:46.010132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-11T17:54:46.010141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T17:54:46.010153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-11T17:54:46.010198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-11T17:54:46.011371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T17:54:46.011399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:54:46.011535Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T17:54:46.011819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:54:46.011893Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T17:54:46.01192Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T17:54:46.013268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T17:54:46.013313Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:54:46.0135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:54:46.013547Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T17:54:46.01402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"warn","ts":"2026-05-11T17:54:47.892857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291421653246335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T17:54:48.209406Z","caller":"traceutil/trace.go:171","msg":"trace[1864440734] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"849.992817ms","start":"2026-05-11T17:54:47.359367Z","end":"2026-05-11T17:54:48.20936Z","steps":["trace[1864440734] 'process raft request' (duration: 849.616877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.656889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.209566Z","caller":"traceutil/trace.go:171","msg":"trace[1832120567] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"831.839507ms","start":"2026-05-11T17:54:47.37756Z","end":"2026-05-11T17:54:48.209399Z","steps":["trace[1832120567] 'process raft request' (duration: 831.677343ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:54:48.209673Z","caller":"traceutil/trace.go:171","msg":"trace[1553243667] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"914.814933ms","start":"2026-05-11T17:54:47.294843Z","end":"2026-05-11T17:54:48.209658Z","steps":["trace[1553243667] 'agreement among raft nodes before linearized reading' (duration: 914.640818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.463821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.209712Z","caller":"traceutil/trace.go:171","msg":"trace[1403408516] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"832.044503ms","start":"2026-05-11T17:54:47.377644Z","end":"2026-05-11T17:54:48.209688Z","steps":["trace[1403408516] 'process raft request' (duration: 831.627162ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:54:48.209731Z","caller":"traceutil/trace.go:171","msg":"trace[196641948] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"861.624525ms","start":"2026-05-11T17:54:47.348085Z","end":"2026-05-11T17:54:48.209709Z","steps":["trace[196641948] 'agreement among raft nodes before linearized reading' (duration: 861.43761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.294833Z","time spent":"914.875704ms","remote":"127.0.0.1:42590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-11T17:54:48.209787Z","caller":"traceutil/trace.go:171","msg":"trace[661183339] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"837.078045ms","start":"2026-05-11T17:54:47.372686Z","end":"2026-05-11T17:54:48.209764Z","steps":["trace[661183339] 'process raft request' (duration: 836.474049ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:54:48.209791Z","caller":"traceutil/trace.go:171","msg":"trace[424653290] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"832.276588ms","start":"2026-05-11T17:54:47.377506Z","end":"2026-05-11T17:54:48.209782Z","steps":["trace[424653290] 'process raft request' (duration: 831.793345ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:54:48.209822Z","caller":"traceutil/trace.go:171","msg":"trace[1762992267] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"831.388014ms","start":"2026-05-11T17:54:47.378427Z","end":"2026-05-11T17:54:48.209815Z","steps":["trace[1762992267] 'process raft request' (duration: 830.903001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.348061Z","time spent":"861.718687ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-11T17:54:48.209845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.347723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-11T17:54:48.209853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.24096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.209876Z","caller":"traceutil/trace.go:171","msg":"trace[1982764655] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"851.394153ms","start":"2026-05-11T17:54:47.358473Z","end":"2026-05-11T17:54:48.209867Z","steps":["trace[1982764655] 'agreement among raft nodes before linearized reading' (duration: 851.345462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.35846Z","time spent":"851.452915ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-11T17:54:48.209929Z","caller":"traceutil/trace.go:171","msg":"trace[1051631214] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:0; response_revision:10; }","duration":"848.353903ms","start":"2026-05-11T17:54:47.361554Z","end":"2026-05-11T17:54:48.209908Z","steps":["trace[1051631214] 'agreement among raft nodes before linearized reading' (duration: 848.203479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.36154Z","time spent":"848.418535ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-05-11T17:54:48.210069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.114125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.210095Z","caller":"traceutil/trace.go:171","msg":"trace[1062031530] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"914.148767ms","start":"2026-05-11T17:54:47.295938Z","end":"2026-05-11T17:54:48.210087Z","steps":["trace[1062031530] 'agreement among raft nodes before linearized reading' (duration: 914.109466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.210127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.295933Z","time spent":"914.188738ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-11T17:54:48.210124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.359347Z","time spent":"850.196752ms","remote":"127.0.0.1:42644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T17:54:48.210136Z","caller":"traceutil/trace.go:171","msg":"trace[1112927952] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"832.756372ms","start":"2026-05-11T17:54:47.377364Z","end":"2026-05-11T17:54:48.21012Z","steps":["trace[1112927952] 'process raft request' (duration: 831.839357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.209591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.92874ms","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":"info","ts":"2026-05-11T17:54:48.210204Z","caller":"traceutil/trace.go:171","msg":"trace[1857230970] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"829.290589ms","start":"2026-05-11T17:54:47.380901Z","end":"2026-05-11T17:54:48.210191Z","steps":["trace[1857230970] 'process raft request' (duration: 828.453776ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:54:48.210224Z","caller":"traceutil/trace.go:171","msg":"trace[577331261] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"846.626259ms","start":"2026-05-11T17:54:47.363584Z","end":"2026-05-11T17:54:48.21021Z","steps":["trace[577331261] 'agreement among raft nodes before linearized reading' (duration: 845.823487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.210266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.363565Z","time spent":"846.691351ms","remote":"127.0.0.1:42298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-11T17:54:48.210283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.561497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.209425Z","caller":"traceutil/trace.go:171","msg":"trace[562870646] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"817.223449ms","start":"2026-05-11T17:54:47.392171Z","end":"2026-05-11T17:54:48.209395Z","steps":["trace[562870646] 'read index received' (duration: 816.780377ms)","trace[562870646] 'applied index is now lower than readState.Index' (duration: 441.952µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:54:48.210339Z","caller":"traceutil/trace.go:171","msg":"trace[828226511] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"866.654239ms","start":"2026-05-11T17:54:47.343676Z","end":"2026-05-11T17:54:48.21033Z","steps":["trace[828226511] 'agreement among raft nodes before linearized reading' (duration: 866.584028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.210338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.380884Z","time spent":"829.354571ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.21013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.097798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-11T17:54:48.210379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.343647Z","time spent":"866.718821ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-11T17:54:48.210396Z","caller":"traceutil/trace.go:171","msg":"trace[1452964019] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"862.378776ms","start":"2026-05-11T17:54:47.348003Z","end":"2026-05-11T17:54:48.210382Z","steps":["trace[1452964019] 'agreement among raft nodes before linearized reading' (duration: 862.028627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.210437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.347994Z","time spent":"862.431897ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-11T17:54:48.210474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.560118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T17:54:48.210509Z","caller":"traceutil/trace.go:171","msg":"trace[310311658] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"800.618921ms","start":"2026-05-11T17:54:47.409879Z","end":"2026-05-11T17:54:48.210498Z","steps":["trace[310311658] 'agreement among raft nodes before linearized reading' (duration: 800.539689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:54:48.210281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.377346Z","time spent":"832.831803ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.21054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.409865Z","time spent":"800.667782ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-11T17:54:48.210549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.377546Z","time spent":"832.155655ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.21064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.377633Z","time spent":"832.133425ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.210721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.372666Z","time spent":"837.151816ms","remote":"127.0.0.1:42404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.210831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.377446Z","time spent":"832.388261ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T17:54:48.210944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:54:47.378415Z","time spent":"831.435545ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T17:55:15.548918Z","caller":"traceutil/trace.go:171","msg":"trace[1528251973] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"125.257667ms","start":"2026-05-11T17:55:15.423641Z","end":"2026-05-11T17:55:15.548899Z","steps":["trace[1528251973] 'read index received' (duration: 125.106113ms)","trace[1528251973] 'applied index is now lower than readState.Index' (duration: 150.884µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:55:15.549015Z","caller":"traceutil/trace.go:171","msg":"trace[1166813128] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"205.179964ms","start":"2026-05-11T17:55:15.343796Z","end":"2026-05-11T17:55:15.548976Z","steps":["trace[1166813128] 'process raft request' (duration: 205.0043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:55:15.549077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.425612ms","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-05-11T17:55:15.549145Z","caller":"traceutil/trace.go:171","msg":"trace[1436814395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"125.545455ms","start":"2026-05-11T17:55:15.423581Z","end":"2026-05-11T17:55:15.549127Z","steps":["trace[1436814395] 'agreement among raft nodes before linearized reading' (duration: 125.398461ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.962641Z","caller":"traceutil/trace.go:171","msg":"trace[1683221858] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"161.323452ms","start":"2026-05-11T17:55:15.80129Z","end":"2026-05-11T17:55:15.962614Z","steps":["trace[1683221858] 'process raft request' (duration: 161.144037ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.978748Z","caller":"traceutil/trace.go:171","msg":"trace[582451940] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"165.250246ms","start":"2026-05-11T17:55:15.813466Z","end":"2026-05-11T17:55:15.978716Z","steps":["trace[582451940] 'process raft request' (duration: 165.049561ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979034Z","caller":"traceutil/trace.go:171","msg":"trace[1675237945] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"148.05682ms","start":"2026-05-11T17:55:15.830942Z","end":"2026-05-11T17:55:15.978999Z","steps":["trace[1675237945] 'process raft request' (duration: 147.774203ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979101Z","caller":"traceutil/trace.go:171","msg":"trace[1306693346] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"144.859096ms","start":"2026-05-11T17:55:15.834229Z","end":"2026-05-11T17:55:15.979088Z","steps":["trace[1306693346] 'process raft request' (duration: 144.523057ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979177Z","caller":"traceutil/trace.go:171","msg":"trace[45725538] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"135.842787ms","start":"2026-05-11T17:55:15.843327Z","end":"2026-05-11T17:55:15.97917Z","steps":["trace[45725538] 'process raft request' (duration: 135.455647ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979234Z","caller":"traceutil/trace.go:171","msg":"trace[1019695695] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"131.914682ms","start":"2026-05-11T17:55:15.847313Z","end":"2026-05-11T17:55:15.979228Z","steps":["trace[1019695695] 'process raft request' (duration: 131.562793ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979286Z","caller":"traceutil/trace.go:171","msg":"trace[549245077] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"116.200187ms","start":"2026-05-11T17:55:15.863081Z","end":"2026-05-11T17:55:15.979281Z","steps":["trace[549245077] 'process raft request' (duration: 115.841917ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979295Z","caller":"traceutil/trace.go:171","msg":"trace[900468651] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"162.649137ms","start":"2026-05-11T17:55:15.816638Z","end":"2026-05-11T17:55:15.979287Z","steps":["trace[900468651] 'process raft request' (duration: 161.99025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979358Z","caller":"traceutil/trace.go:171","msg":"trace[618724304] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"159.646817ms","start":"2026-05-11T17:55:15.819702Z","end":"2026-05-11T17:55:15.979349Z","steps":["trace[618724304] 'process raft request' (duration: 158.958219ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:15.979502Z","caller":"traceutil/trace.go:171","msg":"trace[1981829825] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"155.568088ms","start":"2026-05-11T17:55:15.823926Z","end":"2026-05-11T17:55:15.979494Z","steps":["trace[1981829825] 'process raft request' (duration: 154.761817ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:21.33181Z","caller":"traceutil/trace.go:171","msg":"trace[1472982444] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"294.256902ms","start":"2026-05-11T17:55:21.037491Z","end":"2026-05-11T17:55:21.331748Z","steps":["trace[1472982444] 'process raft request' (duration: 202.175293ms)","trace[1472982444] 'compare' (duration: 91.73999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:55:22.178584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.133385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T17:55:22.178701Z","caller":"traceutil/trace.go:171","msg":"trace[2101525392] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"133.477233ms","start":"2026-05-11T17:55:22.045208Z","end":"2026-05-11T17:55:22.178685Z","steps":["trace[2101525392] 'read index received' (duration: 51.001µs)","trace[2101525392] 'applied index is now lower than readState.Index' (duration: 133.425032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:55:22.178873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.663308ms","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-05-11T17:55:22.17891Z","caller":"traceutil/trace.go:171","msg":"trace[1837590473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"133.744111ms","start":"2026-05-11T17:55:22.045156Z","end":"2026-05-11T17:55:22.178901Z","steps":["trace[1837590473] 'agreement among raft nodes before linearized reading' (duration: 133.619948ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:22.178788Z","caller":"traceutil/trace.go:171","msg":"trace[280406683] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"384.83988ms","start":"2026-05-11T17:55:21.793918Z","end":"2026-05-11T17:55:22.178758Z","steps":["trace[280406683] 'process raft request' (duration: 78.14186ms)","trace[280406683] 'compare' (duration: 305.614082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:55:22.179133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:55:21.793881Z","time spent":"385.112277ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:55:22.791673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.423834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:55:22.791763Z","caller":"traceutil/trace.go:171","msg":"trace[1651286428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"181.568028ms","start":"2026-05-11T17:55:22.610174Z","end":"2026-05-11T17:55:22.791742Z","steps":["trace[1651286428] 'range keys from in-memory index tree' (duration: 181.344803ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:22.830948Z","caller":"traceutil/trace.go:171","msg":"trace[1397736847] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"147.588339ms","start":"2026-05-11T17:55:22.683318Z","end":"2026-05-11T17:55:22.830907Z","steps":["trace[1397736847] 'process raft request' (duration: 146.642754ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:55:23.195861Z","caller":"traceutil/trace.go:171","msg":"trace[1430590507] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"359.416467ms","start":"2026-05-11T17:55:22.836416Z","end":"2026-05-11T17:55:23.195833Z","steps":["trace[1430590507] 'process raft request' (duration: 330.81262ms)","trace[1430590507] 'compare' (duration: 27.832787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:55:23.196012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:55:22.836385Z","time spent":"359.550301ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:55:23.196557Z","caller":"traceutil/trace.go:171","msg":"trace[1056987202] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"308.792486ms","start":"2026-05-11T17:55:22.887061Z","end":"2026-05-11T17:55:23.195854Z","steps":["trace[1056987202] 'process raft request' (duration: 308.598221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:55:23.19677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:55:22.887034Z","time spent":"309.625189ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T17:55:40.024773Z","caller":"traceutil/trace.go:171","msg":"trace[299177721] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"295.65053ms","start":"2026-05-11T17:55:39.729093Z","end":"2026-05-11T17:55:40.024744Z","steps":["trace[299177721] 'read index received' (duration: 295.245181ms)","trace[299177721] 'applied index is now lower than readState.Index' (duration: 403.779µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:55:40.025002Z","caller":"traceutil/trace.go:171","msg":"trace[747830854] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"310.792036ms","start":"2026-05-11T17:55:39.714078Z","end":"2026-05-11T17:55:40.02487Z","steps":["trace[747830854] 'process raft request' (duration: 310.400957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:55:40.025097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.977337ms","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-05-11T17:55:40.025165Z","caller":"traceutil/trace.go:171","msg":"trace[1107361300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"296.090899ms","start":"2026-05-11T17:55:39.729062Z","end":"2026-05-11T17:55:40.025152Z","steps":["trace[1107361300] 'agreement among raft nodes before linearized reading' (duration: 295.837453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:55:40.025331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:55:39.714053Z","time spent":"311.067133ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:56:12.710123Z","caller":"traceutil/trace.go:171","msg":"trace[1358127024] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"202.897993ms","start":"2026-05-11T17:56:12.507206Z","end":"2026-05-11T17:56:12.710104Z","steps":["trace[1358127024] 'read index received' (duration: 202.80213ms)","trace[1358127024] 'applied index is now lower than readState.Index' (duration: 95.253µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:56:12.7104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.18176ms","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-05-11T17:56:12.710459Z","caller":"traceutil/trace.go:171","msg":"trace[130401579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"203.283993ms","start":"2026-05-11T17:56:12.507161Z","end":"2026-05-11T17:56:12.710445Z","steps":["trace[130401579] 'agreement among raft nodes before linearized reading' (duration: 203.022796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:12.710278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.220234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:56:12.710674Z","caller":"traceutil/trace.go:171","msg":"trace[622978011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"100.650535ms","start":"2026-05-11T17:56:12.610001Z","end":"2026-05-11T17:56:12.710652Z","steps":["trace[622978011] 'agreement among raft nodes before linearized reading' (duration: 100.217784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:12.710176Z","caller":"traceutil/trace.go:171","msg":"trace[1723210898] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"212.744771ms","start":"2026-05-11T17:56:12.497411Z","end":"2026-05-11T17:56:12.710156Z","steps":["trace[1723210898] 'process raft request' (duration: 212.572877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:14.676962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291421653248261,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T17:56:15.177646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291421653248261,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T17:56:15.275985Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.784689756s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T17:56:15.276414Z","caller":"traceutil/trace.go:171","msg":"trace[614412711] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.785174478s","start":"2026-05-11T17:56:13.491188Z","end":"2026-05-11T17:56:15.276362Z","steps":["trace[614412711] 'process raft request' (duration: 1.785000643s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.276566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:13.491171Z","time spent":"1.785309962s","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:56:15.286393Z","caller":"traceutil/trace.go:171","msg":"trace[1011002675] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.713496961s","start":"2026-05-11T17:56:13.572874Z","end":"2026-05-11T17:56:15.286371Z","steps":["trace[1011002675] 'process raft request' (duration: 1.713370888s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.286531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:13.572849Z","time spent":"1.713603884s","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:56:15.288456Z","caller":"traceutil/trace.go:171","msg":"trace[1323503288] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:993; }","duration":"1.111549288s","start":"2026-05-11T17:56:14.176894Z","end":"2026-05-11T17:56:15.288443Z","steps":["trace[1323503288] 'read index received' (duration: 1.099268275s)","trace[1323503288] 'applied index is now lower than readState.Index' (duration: 12.280213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:56:15.288603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111711422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T17:56:15.288627Z","caller":"traceutil/trace.go:171","msg":"trace[346644809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"1.111778974s","start":"2026-05-11T17:56:14.176841Z","end":"2026-05-11T17:56:15.28862Z","steps":["trace[346644809] 'agreement among raft nodes before linearized reading' (duration: 1.111680742s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.288616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.595864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T17:56:15.288649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:14.176813Z","time spent":"1.111832295s","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T17:56:15.288643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.804137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:56:15.288658Z","caller":"traceutil/trace.go:171","msg":"trace[1473847907] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:961; }","duration":"108.690297ms","start":"2026-05-11T17:56:15.179957Z","end":"2026-05-11T17:56:15.288648Z","steps":["trace[1473847907] 'agreement among raft nodes before linearized reading' (duration: 108.608375ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:15.288679Z","caller":"traceutil/trace.go:171","msg":"trace[288193264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"678.869949ms","start":"2026-05-11T17:56:14.609799Z","end":"2026-05-11T17:56:15.288669Z","steps":["trace[288193264] 'agreement among raft nodes before linearized reading' (duration: 678.816637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.288701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:14.609782Z","time spent":"678.914449ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T17:56:15.288686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107672337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T17:56:15.28874Z","caller":"traceutil/trace.go:171","msg":"trace[411488171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"1.10778228s","start":"2026-05-11T17:56:14.180945Z","end":"2026-05-11T17:56:15.288727Z","steps":["trace[411488171] 'agreement among raft nodes before linearized reading' (duration: 1.107605065s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.288739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.137058ms","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-05-11T17:56:15.288776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:14.180922Z","time spent":"1.107846351s","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T17:56:15.28878Z","caller":"traceutil/trace.go:171","msg":"trace[448153697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"560.20795ms","start":"2026-05-11T17:56:14.728561Z","end":"2026-05-11T17:56:15.288769Z","steps":["trace[448153697] 'agreement among raft nodes before linearized reading' (duration: 560.039926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.288814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:14.728546Z","time spent":"560.260081ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T17:56:15.288976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.06849ms","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-05-11T17:56:15.289001Z","caller":"traceutil/trace.go:171","msg":"trace[2075922446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"546.150362ms","start":"2026-05-11T17:56:14.742843Z","end":"2026-05-11T17:56:15.288994Z","steps":["trace[2075922446] 'agreement among raft nodes before linearized reading' (duration: 545.70423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:15.28902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:14.742782Z","time spent":"546.233983ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T17:56:15.478721Z","caller":"traceutil/trace.go:171","msg":"trace[2082393518] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"186.282609ms","start":"2026-05-11T17:56:15.292411Z","end":"2026-05-11T17:56:15.478693Z","steps":["trace[2082393518] 'process raft request' (duration: 165.571886ms)","trace[2082393518] 'compare' (duration: 20.510377ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:56:15.479074Z","caller":"traceutil/trace.go:171","msg":"trace[1575547273] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"186.066253ms","start":"2026-05-11T17:56:15.292977Z","end":"2026-05-11T17:56:15.479043Z","steps":["trace[1575547273] 'process raft request' (duration: 185.889008ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:15.479349Z","caller":"traceutil/trace.go:171","msg":"trace[1171156741] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"186.832533ms","start":"2026-05-11T17:56:15.292494Z","end":"2026-05-11T17:56:15.479327Z","steps":["trace[1171156741] 'process raft request' (duration: 186.142545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:38.00879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.551028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T17:56:38.008895Z","caller":"traceutil/trace.go:171","msg":"trace[743242467] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"422.145528ms","start":"2026-05-11T17:56:37.586733Z","end":"2026-05-11T17:56:38.008879Z","steps":["trace[743242467] 'read index received' (duration: 147.390705ms)","trace[743242467] 'applied index is now lower than readState.Index' (duration: 274.753363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:56:38.009179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.423564ms","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-05-11T17:56:38.009199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.959016ms","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-05-11T17:56:38.009237Z","caller":"traceutil/trace.go:171","msg":"trace[1266284062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"422.517507ms","start":"2026-05-11T17:56:37.586705Z","end":"2026-05-11T17:56:38.009223Z","steps":["trace[1266284062] 'agreement among raft nodes before linearized reading' (duration: 422.321152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:38.009273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:37.586691Z","time spent":"422.576588ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T17:56:38.009276Z","caller":"traceutil/trace.go:171","msg":"trace[1606210058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"415.064679ms","start":"2026-05-11T17:56:37.594187Z","end":"2026-05-11T17:56:38.009251Z","steps":["trace[1606210058] 'agreement among raft nodes before linearized reading' (duration: 414.864074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:38.00934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:37.594159Z","time spent":"415.167711ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T17:56:38.009404Z","caller":"traceutil/trace.go:171","msg":"trace[1711187058] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"422.429374ms","start":"2026-05-11T17:56:37.586637Z","end":"2026-05-11T17:56:38.009066Z","steps":["trace[1711187058] 'process raft request' (duration: 147.525198ms)","trace[1711187058] 'compare' (duration: 274.457066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:56:38.009592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:37.586624Z","time spent":"422.851154ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:56:38.183159Z","caller":"traceutil/trace.go:171","msg":"trace[1916120937] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"169.717357ms","start":"2026-05-11T17:56:38.013413Z","end":"2026-05-11T17:56:38.18313Z","steps":["trace[1916120937] 'process raft request' (duration: 146.660777ms)","trace[1916120937] 'compare' (duration: 22.942857ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:56:38.194247Z","caller":"traceutil/trace.go:171","msg":"trace[167717631] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"168.759425ms","start":"2026-05-11T17:56:38.025406Z","end":"2026-05-11T17:56:38.194166Z","steps":["trace[167717631] 'read index received' (duration: 134.681782ms)","trace[167717631] 'applied index is now lower than readState.Index' (duration: 34.076483ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:56:38.194347Z","caller":"traceutil/trace.go:171","msg":"trace[981551111] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"180.33373ms","start":"2026-05-11T17:56:38.013995Z","end":"2026-05-11T17:56:38.194329Z","steps":["trace[981551111] 'process raft request' (duration: 180.009873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:38.1944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.985339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:56:38.194455Z","caller":"traceutil/trace.go:171","msg":"trace[455317750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1037; }","duration":"169.093232ms","start":"2026-05-11T17:56:38.025348Z","end":"2026-05-11T17:56:38.194441Z","steps":["trace[455317750] 'agreement among raft nodes before linearized reading' (duration: 168.99029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:38.522388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.796927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T17:56:38.522463Z","caller":"traceutil/trace.go:171","msg":"trace[2144814428] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1037; }","duration":"171.93582ms","start":"2026-05-11T17:56:38.350513Z","end":"2026-05-11T17:56:38.522449Z","steps":["trace[2144814428] 'count revisions from in-memory index tree' (duration: 171.699194ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:39.726413Z","caller":"traceutil/trace.go:171","msg":"trace[431313220] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"181.238562ms","start":"2026-05-11T17:56:39.54515Z","end":"2026-05-11T17:56:39.726388Z","steps":["trace[431313220] 'process raft request' (duration: 181.035177ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:50.400482Z","caller":"traceutil/trace.go:171","msg":"trace[630414575] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"168.962669ms","start":"2026-05-11T17:56:50.2315Z","end":"2026-05-11T17:56:50.400462Z","steps":["trace[630414575] 'read index received' (duration: 168.777565ms)","trace[630414575] 'applied index is now lower than readState.Index' (duration: 184.404µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:56:50.400594Z","caller":"traceutil/trace.go:171","msg":"trace[30189442] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"325.345409ms","start":"2026-05-11T17:56:50.075242Z","end":"2026-05-11T17:56:50.400587Z","steps":["trace[30189442] 'process raft request' (duration: 325.095383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:50.400682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:50.075208Z","time spent":"325.405651ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:56:50.400929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.203566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T17:56:50.401025Z","caller":"traceutil/trace.go:171","msg":"trace[1173507477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"151.34279ms","start":"2026-05-11T17:56:50.249657Z","end":"2026-05-11T17:56:50.400999Z","steps":["trace[1173507477] 'agreement among raft nodes before linearized reading' (duration: 151.076364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:50.401072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.569814ms","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-05-11T17:56:50.401119Z","caller":"traceutil/trace.go:171","msg":"trace[246484769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"169.648216ms","start":"2026-05-11T17:56:50.231457Z","end":"2026-05-11T17:56:50.401106Z","steps":["trace[246484769] 'agreement among raft nodes before linearized reading' (duration: 169.501023ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:56:51.244259Z","caller":"traceutil/trace.go:171","msg":"trace[559962031] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"374.118902ms","start":"2026-05-11T17:56:50.870101Z","end":"2026-05-11T17:56:51.244219Z","steps":["trace[559962031] 'process raft request' (duration: 373.869897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:56:51.244531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:56:50.87008Z","time spent":"374.298416ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:56:51.760443Z","caller":"traceutil/trace.go:171","msg":"trace[365181246] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"120.307379ms","start":"2026-05-11T17:56:51.640099Z","end":"2026-05-11T17:56:51.760407Z","steps":["trace[365181246] 'process raft request' (duration: 61.269041ms)","trace[365181246] 'compare' (duration: 58.807593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:09.989359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.464512ms","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-05-11T17:57:09.989455Z","caller":"traceutil/trace.go:171","msg":"trace[430991678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"125.605886ms","start":"2026-05-11T17:57:09.86383Z","end":"2026-05-11T17:57:09.989435Z","steps":["trace[430991678] 'range keys from in-memory index tree' (duration: 125.304258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:10.891638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.446719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T17:57:10.891704Z","caller":"traceutil/trace.go:171","msg":"trace[711931881] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"379.335036ms","start":"2026-05-11T17:57:10.512358Z","end":"2026-05-11T17:57:10.891693Z","steps":["trace[711931881] 'read index received' (duration: 272.824495ms)","trace[711931881] 'applied index is now lower than readState.Index' (duration: 106.509631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:10.891805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.445538ms","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-05-11T17:57:10.891819Z","caller":"traceutil/trace.go:171","msg":"trace[1123964408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"379.4788ms","start":"2026-05-11T17:57:10.512335Z","end":"2026-05-11T17:57:10.891813Z","steps":["trace[1123964408] 'agreement among raft nodes before linearized reading' (duration: 379.403878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:10.891836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:10.512324Z","time spent":"379.50754ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T17:57:10.891884Z","caller":"traceutil/trace.go:171","msg":"trace[481859161] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"380.616617ms","start":"2026-05-11T17:57:10.511227Z","end":"2026-05-11T17:57:10.891844Z","steps":["trace[481859161] 'process raft request' (duration: 273.907062ms)","trace[481859161] 'compare' (duration: 106.086791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:10.89204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:10.511207Z","time spent":"380.74495ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:57:11.337939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.305172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T17:57:11.338038Z","caller":"traceutil/trace.go:171","msg":"trace[10305324] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"276.213337ms","start":"2026-05-11T17:57:11.06181Z","end":"2026-05-11T17:57:11.338023Z","steps":["trace[10305324] 'read index received' (duration: 84.70134ms)","trace[10305324] 'applied index is now lower than readState.Index' (duration: 191.510707ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:11.338072Z","caller":"traceutil/trace.go:171","msg":"trace[251956321] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"441.319574ms","start":"2026-05-11T17:57:10.896725Z","end":"2026-05-11T17:57:11.338044Z","steps":["trace[251956321] 'process raft request' (duration: 249.838268ms)","trace[251956321] 'compare' (duration: 191.187099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:11.338275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.448213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T17:57:11.338323Z","caller":"traceutil/trace.go:171","msg":"trace[2047305555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1222; }","duration":"276.533185ms","start":"2026-05-11T17:57:11.061782Z","end":"2026-05-11T17:57:11.338315Z","steps":["trace[2047305555] 'agreement among raft nodes before linearized reading' (duration: 276.444663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:11.338451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.942184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:57:11.338525Z","caller":"traceutil/trace.go:171","msg":"trace[492188158] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1222; }","duration":"217.071207ms","start":"2026-05-11T17:57:11.121435Z","end":"2026-05-11T17:57:11.338506Z","steps":["trace[492188158] 'agreement among raft nodes before linearized reading' (duration: 216.935854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:11.33853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:10.89671Z","time spent":"441.423097ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:57:12.049552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.403522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T17:57:12.049663Z","caller":"traceutil/trace.go:171","msg":"trace[2127010433] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"251.506818ms","start":"2026-05-11T17:57:11.79814Z","end":"2026-05-11T17:57:12.049647Z","steps":["trace[2127010433] 'read index received' (duration: 119.935071ms)","trace[2127010433] 'applied index is now lower than readState.Index' (duration: 131.570687ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:12.049764Z","caller":"traceutil/trace.go:171","msg":"trace[1609703168] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"264.268973ms","start":"2026-05-11T17:57:11.785485Z","end":"2026-05-11T17:57:12.049754Z","steps":["trace[1609703168] 'process raft request' (duration: 132.594923ms)","trace[1609703168] 'compare' (duration: 131.220858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:12.049967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.750256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:57:12.050029Z","caller":"traceutil/trace.go:171","msg":"trace[1621871826] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1227; }","duration":"137.854138ms","start":"2026-05-11T17:57:11.91216Z","end":"2026-05-11T17:57:12.050015Z","steps":["trace[1621871826] 'agreement among raft nodes before linearized reading' (duration: 137.705704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:12.050077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.922278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:57:12.050203Z","caller":"traceutil/trace.go:171","msg":"trace[653783134] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1227; }","duration":"252.089552ms","start":"2026-05-11T17:57:11.798096Z","end":"2026-05-11T17:57:12.050185Z","steps":["trace[653783134] 'agreement among raft nodes before linearized reading' (duration: 251.923278ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:12.232486Z","caller":"traceutil/trace.go:171","msg":"trace[763332281] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"179.062551ms","start":"2026-05-11T17:57:12.053387Z","end":"2026-05-11T17:57:12.232449Z","steps":["trace[763332281] 'process raft request' (duration: 105.755703ms)","trace[763332281] 'compare' (duration: 73.142884ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:12.241792Z","caller":"traceutil/trace.go:171","msg":"trace[1744363906] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"183.603399ms","start":"2026-05-11T17:57:12.058169Z","end":"2026-05-11T17:57:12.241773Z","steps":["trace[1744363906] 'process raft request' (duration: 183.466405ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:12.241774Z","caller":"traceutil/trace.go:171","msg":"trace[1963599374] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"188.2844ms","start":"2026-05-11T17:57:12.053443Z","end":"2026-05-11T17:57:12.241728Z","steps":["trace[1963599374] 'process raft request' (duration: 188.014603ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:12.241979Z","caller":"traceutil/trace.go:171","msg":"trace[1331964706] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"185.873363ms","start":"2026-05-11T17:57:12.05607Z","end":"2026-05-11T17:57:12.241943Z","steps":["trace[1331964706] 'process raft request' (duration: 185.523994ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:13.00188Z","caller":"traceutil/trace.go:171","msg":"trace[371861417] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"102.009672ms","start":"2026-05-11T17:57:12.899855Z","end":"2026-05-11T17:57:13.001864Z","steps":["trace[371861417] 'process raft request' (duration: 101.899809ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:23.438508Z","caller":"traceutil/trace.go:171","msg":"trace[780068034] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"416.643836ms","start":"2026-05-11T17:57:23.021845Z","end":"2026-05-11T17:57:23.438489Z","steps":["trace[780068034] 'process raft request' (duration: 416.420031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.438679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.021823Z","time spent":"416.77303ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:57:23.438824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.205058ms","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-05-11T17:57:23.438929Z","caller":"traceutil/trace.go:171","msg":"trace[1179353498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"396.345972ms","start":"2026-05-11T17:57:23.042558Z","end":"2026-05-11T17:57:23.438904Z","steps":["trace[1179353498] 'agreement among raft nodes before linearized reading' (duration: 396.094526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.43899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.042545Z","time spent":"396.430884ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T17:57:23.439061Z","caller":"traceutil/trace.go:171","msg":"trace[1370601228] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"395.83046ms","start":"2026-05-11T17:57:23.0426Z","end":"2026-05-11T17:57:23.43843Z","steps":["trace[1370601228] 'read index received' (duration: 395.588574ms)","trace[1370601228] 'applied index is now lower than readState.Index' (duration: 240.706µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T17:57:23.439349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.799799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-11T17:57:23.439395Z","caller":"traceutil/trace.go:171","msg":"trace[1382921331] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"130.887831ms","start":"2026-05-11T17:57:23.308493Z","end":"2026-05-11T17:57:23.439381Z","steps":["trace[1382921331] 'agreement among raft nodes before linearized reading' (duration: 130.669416ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:23.586662Z","caller":"traceutil/trace.go:171","msg":"trace[304804987] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"142.807235ms","start":"2026-05-11T17:57:23.443828Z","end":"2026-05-11T17:57:23.586635Z","steps":["trace[304804987] 'process raft request' (duration: 123.2748ms)","trace[304804987] 'compare' (duration: 19.402242ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:23.984579Z","caller":"traceutil/trace.go:171","msg":"trace[747909190] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"374.886581ms","start":"2026-05-11T17:57:23.609668Z","end":"2026-05-11T17:57:23.984555Z","steps":["trace[747909190] 'read index received' (duration: 322.89792ms)","trace[747909190] 'applied index is now lower than readState.Index' (duration: 51.987081ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:23.984865Z","caller":"traceutil/trace.go:171","msg":"trace[1159169298] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"540.919089ms","start":"2026-05-11T17:57:23.443922Z","end":"2026-05-11T17:57:23.984841Z","steps":["trace[1159169298] 'process raft request' (duration: 488.640282ms)","trace[1159169298] 'compare' (duration: 51.695674ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T17:57:23.985096Z","caller":"traceutil/trace.go:171","msg":"trace[124754600] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"539.819724ms","start":"2026-05-11T17:57:23.445246Z","end":"2026-05-11T17:57:23.985066Z","steps":["trace[124754600] 'process raft request' (duration: 539.249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.985177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.486114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T17:57:23.985656Z","caller":"traceutil/trace.go:171","msg":"trace[1081176293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"376.049468ms","start":"2026-05-11T17:57:23.609587Z","end":"2026-05-11T17:57:23.985637Z","steps":["trace[1081176293] 'agreement among raft nodes before linearized reading' (duration: 375.496215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.985749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.609569Z","time spent":"376.16593ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T17:57:23.985935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.445236Z","time spent":"540.329915ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T17:57:23.986465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.443905Z","time spent":"541.490433ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T17:57:23.991011Z","caller":"traceutil/trace.go:171","msg":"trace[2125650977] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"277.637131ms","start":"2026-05-11T17:57:23.713359Z","end":"2026-05-11T17:57:23.990996Z","steps":["trace[2125650977] 'process raft request' (duration: 277.503348ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T17:57:23.99118Z","caller":"traceutil/trace.go:171","msg":"trace[15549718] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"219.648678ms","start":"2026-05-11T17:57:23.7715Z","end":"2026-05-11T17:57:23.991149Z","steps":["trace[15549718] 'process raft request' (duration: 219.461673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.99137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.988146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-11T17:57:23.991442Z","caller":"traceutil/trace.go:171","msg":"trace[2094576791] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"324.084748ms","start":"2026-05-11T17:57:23.667339Z","end":"2026-05-11T17:57:23.991424Z","steps":["trace[2094576791] 'agreement among raft nodes before linearized reading' (duration: 323.895424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T17:57:23.99149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T17:57:23.667328Z","time spent":"324.150969ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-11T18:00:31.900796Z","caller":"traceutil/trace.go:171","msg":"trace[775747073] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"148.946019ms","start":"2026-05-11T18:00:31.751828Z","end":"2026-05-11T18:00:31.900774Z","steps":["trace[775747073] 'process raft request' (duration: 148.788595ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:31.902523Z","caller":"traceutil/trace.go:171","msg":"trace[773661398] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"150.166027ms","start":"2026-05-11T18:00:31.752345Z","end":"2026-05-11T18:00:31.902511Z","steps":["trace[773661398] 'process raft request' (duration: 150.100576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:39.407616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.096846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T18:00:39.40773Z","caller":"traceutil/trace.go:171","msg":"trace[1829016192] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2320; }","duration":"340.025052ms","start":"2026-05-11T18:00:39.067688Z","end":"2026-05-11T18:00:39.407713Z","steps":["trace[1829016192] 'read index received' (duration: 1.67233ms)","trace[1829016192] 'applied index is now lower than readState.Index' (duration: 338.351672ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T18:00:39.407822Z","caller":"traceutil/trace.go:171","msg":"trace[132607831] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"363.511734ms","start":"2026-05-11T18:00:39.044271Z","end":"2026-05-11T18:00:39.407783Z","steps":["trace[132607831] 'process raft request' (duration: 25.142622ms)","trace[132607831] 'compare' (duration: 337.89589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T18:00:39.408011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:00:39.044255Z","time spent":"363.660688ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T18:00:39.408096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.41112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-11T18:00:39.408171Z","caller":"traceutil/trace.go:171","msg":"trace[1423143611] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2230; }","duration":"340.518403ms","start":"2026-05-11T18:00:39.067634Z","end":"2026-05-11T18:00:39.408152Z","steps":["trace[1423143611] 'agreement among raft nodes before linearized reading' (duration: 340.211526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T18:00:39.408217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T18:00:39.067612Z","time spent":"340.595174ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T18:00:39.408772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.629047ms","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-05-11T18:00:39.408974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.951546ms","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-05-11T18:00:39.409026Z","caller":"traceutil/trace.go:171","msg":"trace[1850409962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2230; }","duration":"252.026548ms","start":"2026-05-11T18:00:39.156988Z","end":"2026-05-11T18:00:39.409015Z","steps":["trace[1850409962] 'agreement among raft nodes before linearized reading' (duration: 251.909085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T18:00:39.408829Z","caller":"traceutil/trace.go:171","msg":"trace[149858079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2230; }","duration":"259.7692ms","start":"2026-05-11T18:00:39.149049Z","end":"2026-05-11T18:00:39.408818Z","steps":["trace[149858079] 'agreement among raft nodes before linearized reading' (duration: 259.639477ms)"],"step_count":1}