{"level":"warn","ts":"2026-04-04T10:10:43.937271Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:10:43.937462Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-04-04T10:10:43.937645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:10:43.937677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-04T10:10:43.937804Z","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-04-04T10:10:43.938926Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-04-04T10:10:43.939555Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-04-04T10:10:43.949044Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.08534ms"} {"level":"info","ts":"2026-04-04T10:10:43.955564Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-04-04T10:10:43.955634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:10:43.955656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-04-04T10:10:43.955666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:10:43.955681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-04-04T10:10:43.955718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-04-04T10:10:43.960782Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:10:43.962164Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:10:43.963173Z","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-04-04T10:10:43.965747Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:10:43.966129Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:10:43.966838Z","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-04-04T10:10:43.967244Z","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-04-04T10:10:43.967342Z","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-04-04T10:10:43.968228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-04-04T10:10:43.9685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-04T10:10:43.970958Z","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-04-04T10:10:43.971157Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-04T10:10:43.971235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-04T10:10:43.971438Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:10:43.971478Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:10:44.856519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:10:44.856583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:10:44.85661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-04-04T10:10:44.856632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:10:44.856646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-04T10:10:44.856677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-04-04T10:10:44.8567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-04T10:10:44.857815Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:10:44.857964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:10:44.857992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:10:44.858121Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:10:44.858056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:10:44.858421Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:10:44.858986Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:10:44.85912Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:10:44.859193Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:10:44.860249Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:10:44.860758Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-04-04T10:10:47.295731Z","caller":"traceutil/trace.go:171","msg":"trace[1156353568] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"107.194566ms","start":"2026-04-04T10:10:47.188501Z","end":"2026-04-04T10:10:47.295696Z","steps":["trace[1156353568] 'read index received' (duration: 107.063132ms)","trace[1156353568] 'applied index is now lower than readState.Index' (duration: 130.314µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:10:47.295991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.464014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-04T10:10:47.296052Z","caller":"traceutil/trace.go:171","msg":"trace[2003409830] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"107.606958ms","start":"2026-04-04T10:10:47.188429Z","end":"2026-04-04T10:10:47.296036Z","steps":["trace[2003409830] 'agreement among raft nodes before linearized reading' (duration: 107.442203ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:10:47.296208Z","caller":"traceutil/trace.go:171","msg":"trace[809202203] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"109.009719ms","start":"2026-04-04T10:10:47.186802Z","end":"2026-04-04T10:10:47.295812Z","steps":["trace[809202203] 'process raft request' (duration: 108.705741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:10:47.714119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.414335ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-04T10:10:47.714243Z","caller":"traceutil/trace.go:171","msg":"trace[1826181992] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"418.387559ms","start":"2026-04-04T10:10:47.295838Z","end":"2026-04-04T10:10:47.714225Z","steps":["trace[1826181992] 'read index received' (duration: 150.764798ms)","trace[1826181992] 'applied index is now lower than readState.Index' (duration: 267.621381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:10:47.714306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:10:47.192237Z","time spent":"522.041094ms","remote":"127.0.0.1:45284","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-04T10:10:47.714475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.285354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-04T10:10:47.714551Z","caller":"traceutil/trace.go:171","msg":"trace[122406625] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:71; }","duration":"411.443599ms","start":"2026-04-04T10:10:47.303082Z","end":"2026-04-04T10:10:47.714526Z","steps":["trace[122406625] 'agreement among raft nodes before linearized reading' (duration: 411.285125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:10:47.714557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.023857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-04T10:10:47.714595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:10:47.303054Z","time spent":"411.532911ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-04-04T10:10:47.714586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.466254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-04T10:10:47.714594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.972844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-04T10:10:47.714665Z","caller":"traceutil/trace.go:171","msg":"trace[1694525833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"427.103429ms","start":"2026-04-04T10:10:47.287548Z","end":"2026-04-04T10:10:47.714651Z","steps":["trace[1694525833] 'agreement among raft nodes before linearized reading' (duration: 426.870492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:10:47.71474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:10:47.287516Z","time spent":"427.212372ms","remote":"127.0.0.1:45188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-04T10:10:47.714666Z","caller":"traceutil/trace.go:171","msg":"trace[1503563980] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:71; }","duration":"161.671199ms","start":"2026-04-04T10:10:47.552972Z","end":"2026-04-04T10:10:47.714643Z","steps":["trace[1503563980] 'agreement among raft nodes before linearized reading' (duration: 161.468444ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:10:47.714668Z","caller":"traceutil/trace.go:171","msg":"trace[1714811612] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:71; }","duration":"411.159191ms","start":"2026-04-04T10:10:47.303472Z","end":"2026-04-04T10:10:47.714631Z","steps":["trace[1714811612] 'agreement among raft nodes before linearized reading' (duration: 410.980696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:10:47.715106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:10:47.303463Z","time spent":"411.623565ms","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"info","ts":"2026-04-04T10:10:51.036752Z","caller":"traceutil/trace.go:171","msg":"trace[566426612] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"143.532541ms","start":"2026-04-04T10:10:50.893179Z","end":"2026-04-04T10:10:51.036711Z","steps":["trace[566426612] 'process raft request' (duration: 143.331345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:11:19.908338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.022493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:11:19.908938Z","caller":"traceutil/trace.go:171","msg":"trace[1095616651] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"285.358103ms","start":"2026-04-04T10:11:19.623559Z","end":"2026-04-04T10:11:19.908917Z","steps":["trace[1095616651] 'process raft request' (duration: 285.22624ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.909095Z","caller":"traceutil/trace.go:171","msg":"trace[470845542] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"289.023274ms","start":"2026-04-04T10:11:19.619984Z","end":"2026-04-04T10:11:19.909007Z","steps":["trace[470845542] 'process raft request' (duration: 185.890356ms)","trace[470845542] 'compare' (duration: 101.827159ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:11:19.911801Z","caller":"traceutil/trace.go:171","msg":"trace[291268949] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"245.550755ms","start":"2026-04-04T10:11:19.666151Z","end":"2026-04-04T10:11:19.911702Z","steps":["trace[291268949] 'process raft request' (duration: 245.191777ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.911949Z","caller":"traceutil/trace.go:171","msg":"trace[2030854301] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"270.851263ms","start":"2026-04-04T10:11:19.641089Z","end":"2026-04-04T10:11:19.91194Z","steps":["trace[2030854301] 'process raft request' (duration: 269.916532ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912016Z","caller":"traceutil/trace.go:171","msg":"trace[790104981] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"237.979467ms","start":"2026-04-04T10:11:19.674024Z","end":"2026-04-04T10:11:19.912003Z","steps":["trace[790104981] 'process raft request' (duration: 237.427055ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912098Z","caller":"traceutil/trace.go:171","msg":"trace[1034105965] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"229.912528ms","start":"2026-04-04T10:11:19.682167Z","end":"2026-04-04T10:11:19.91208Z","steps":["trace[1034105965] 'process raft request' (duration: 229.319345ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912184Z","caller":"traceutil/trace.go:171","msg":"trace[2015248102] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"227.395903ms","start":"2026-04-04T10:11:19.684768Z","end":"2026-04-04T10:11:19.912163Z","steps":["trace[2015248102] 'process raft request' (duration: 226.871901ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912239Z","caller":"traceutil/trace.go:171","msg":"trace[488451618] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"241.871462ms","start":"2026-04-04T10:11:19.670358Z","end":"2026-04-04T10:11:19.91223Z","steps":["trace[488451618] 'process raft request' (duration: 241.062745ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912316Z","caller":"traceutil/trace.go:171","msg":"trace[37337944] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"230.016511ms","start":"2026-04-04T10:11:19.682288Z","end":"2026-04-04T10:11:19.912305Z","steps":["trace[37337944] 'process raft request' (duration: 229.310605ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912203Z","caller":"traceutil/trace.go:171","msg":"trace[1058697196] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"244.074301ms","start":"2026-04-04T10:11:19.668117Z","end":"2026-04-04T10:11:19.912191Z","steps":["trace[1058697196] 'process raft request' (duration: 243.258124ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.911923Z","caller":"traceutil/trace.go:171","msg":"trace[1356099449] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"245.864022ms","start":"2026-04-04T10:11:19.666022Z","end":"2026-04-04T10:11:19.911886Z","steps":["trace[1356099449] 'process raft request' (duration: 245.282219ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.91247Z","caller":"traceutil/trace.go:171","msg":"trace[164872628] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"226.454102ms","start":"2026-04-04T10:11:19.686001Z","end":"2026-04-04T10:11:19.912455Z","steps":["trace[164872628] 'process raft request' (duration: 225.693745ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.91181Z","caller":"traceutil/trace.go:171","msg":"trace[1334177189] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"251.679469ms","start":"2026-04-04T10:11:19.660102Z","end":"2026-04-04T10:11:19.911781Z","steps":["trace[1334177189] 'process raft request' (duration: 251.070776ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.912838Z","caller":"traceutil/trace.go:171","msg":"trace[205117218] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"226.613366ms","start":"2026-04-04T10:11:19.686216Z","end":"2026-04-04T10:11:19.91283Z","steps":["trace[205117218] 'process raft request' (duration: 226.090735ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.923539Z","caller":"traceutil/trace.go:171","msg":"trace[666045169] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"111.914112ms","start":"2026-04-04T10:11:19.811604Z","end":"2026-04-04T10:11:19.923518Z","steps":["trace[666045169] 'process raft request' (duration: 111.78671ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:19.923814Z","caller":"traceutil/trace.go:171","msg":"trace[1941959448] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"224.376096ms","start":"2026-04-04T10:11:19.69942Z","end":"2026-04-04T10:11:19.923797Z","steps":["trace[1941959448] 'process raft request' (duration: 213.422554ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:26.28814Z","caller":"traceutil/trace.go:171","msg":"trace[2042126439] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"145.233715ms","start":"2026-04-04T10:11:26.142876Z","end":"2026-04-04T10:11:26.28811Z","steps":["trace[2042126439] 'process raft request' (duration: 138.281198ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:26.657457Z","caller":"traceutil/trace.go:171","msg":"trace[762559442] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"219.954698ms","start":"2026-04-04T10:11:26.437454Z","end":"2026-04-04T10:11:26.657409Z","steps":["trace[762559442] 'process raft request' (duration: 202.102611ms)","trace[762559442] 'compare' (duration: 17.639642ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:11:27.141114Z","caller":"traceutil/trace.go:171","msg":"trace[1594635536] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"454.829687ms","start":"2026-04-04T10:11:26.686254Z","end":"2026-04-04T10:11:27.141084Z","steps":["trace[1594635536] 'process raft request' (duration: 428.901516ms)","trace[1594635536] 'compare' (duration: 25.267768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:11:27.141473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:11:26.686237Z","time spent":"454.964001ms","remote":"127.0.0.1:45436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:11:27.150726Z","caller":"traceutil/trace.go:171","msg":"trace[1427550170] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"303.185607ms","start":"2026-04-04T10:11:26.847509Z","end":"2026-04-04T10:11:27.150694Z","steps":["trace[1427550170] 'read index received' (duration: 267.212125ms)","trace[1427550170] 'applied index is now lower than readState.Index' (duration: 35.972332ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:11:27.150832Z","caller":"traceutil/trace.go:171","msg":"trace[2073827830] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"324.134478ms","start":"2026-04-04T10:11:26.826649Z","end":"2026-04-04T10:11:27.150783Z","steps":["trace[2073827830] 'process raft request' (duration: 323.520035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:11:27.15107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:11:26.826627Z","time spent":"324.301052ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:11:27.151169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.615696ms","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-04-04T10:11:27.151268Z","caller":"traceutil/trace.go:171","msg":"trace[766846787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"303.761038ms","start":"2026-04-04T10:11:26.847485Z","end":"2026-04-04T10:11:27.151246Z","steps":["trace[766846787] 'agreement among raft nodes before linearized reading' (duration: 303.296609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:11:27.151321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:11:26.847465Z","time spent":"303.842481ms","remote":"127.0.0.1:45436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-04T10:11:28.660368Z","caller":"traceutil/trace.go:171","msg":"trace[749266662] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"106.353467ms","start":"2026-04-04T10:11:28.553978Z","end":"2026-04-04T10:11:28.660332Z","steps":["trace[749266662] 'process raft request' (duration: 43.770568ms)","trace[749266662] 'compare' (duration: 62.067178ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:11:42.671521Z","caller":"traceutil/trace.go:171","msg":"trace[1627300626] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"151.542152ms","start":"2026-04-04T10:11:42.519956Z","end":"2026-04-04T10:11:42.671498Z","steps":["trace[1627300626] 'process raft request' (duration: 151.294818ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:42.671488Z","caller":"traceutil/trace.go:171","msg":"trace[1015393593] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"138.470884ms","start":"2026-04-04T10:11:42.532984Z","end":"2026-04-04T10:11:42.671455Z","steps":["trace[1015393593] 'read index received' (duration: 138.171178ms)","trace[1015393593] 'applied index is now lower than readState.Index' (duration: 298.616µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:11:42.672025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.012363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sbbb7\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-04T10:11:42.672056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.308556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:11:42.672311Z","caller":"traceutil/trace.go:171","msg":"trace[1433291226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"111.597463ms","start":"2026-04-04T10:11:42.560689Z","end":"2026-04-04T10:11:42.672286Z","steps":["trace[1433291226] 'agreement among raft nodes before linearized reading' (duration: 111.309407ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:42.672894Z","caller":"traceutil/trace.go:171","msg":"trace[1745201614] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sbbb7; range_end:; response_count:1; response_revision:794; }","duration":"139.113265ms","start":"2026-04-04T10:11:42.532969Z","end":"2026-04-04T10:11:42.672082Z","steps":["trace[1745201614] 'agreement among raft nodes before linearized reading' (duration: 138.596326ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:11:42.795651Z","caller":"traceutil/trace.go:171","msg":"trace[2024891431] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"113.847856ms","start":"2026-04-04T10:11:42.681764Z","end":"2026-04-04T10:11:42.795612Z","steps":["trace[2024891431] 'process raft request' (duration: 113.57555ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:12:13.002516Z","caller":"traceutil/trace.go:171","msg":"trace[2133402508] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"155.694279ms","start":"2026-04-04T10:12:12.84676Z","end":"2026-04-04T10:12:13.002454Z","steps":["trace[2133402508] 'process raft request' (duration: 155.300492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:03.059956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.177624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a67d100a-2621-41d9-8ab4-a6704802bdca\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-04T10:13:03.06008Z","caller":"traceutil/trace.go:171","msg":"trace[124693944] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a67d100a-2621-41d9-8ab4-a6704802bdca; range_end:; response_count:1; response_revision:1868; }","duration":"111.358756ms","start":"2026-04-04T10:13:02.948702Z","end":"2026-04-04T10:13:03.06006Z","steps":["trace[124693944] 'range keys from in-memory index tree' (duration: 110.937111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:03.060601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.592158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-04T10:13:03.060675Z","caller":"traceutil/trace.go:171","msg":"trace[409822779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1868; }","duration":"201.73546ms","start":"2026-04-04T10:13:02.858923Z","end":"2026-04-04T10:13:03.060658Z","steps":["trace[409822779] 'range keys from in-memory index tree' (duration: 201.433026ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:03.212889Z","caller":"traceutil/trace.go:171","msg":"trace[148218194] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"162.038442ms","start":"2026-04-04T10:13:03.050818Z","end":"2026-04-04T10:13:03.212857Z","steps":["trace[148218194] 'process raft request' (duration: 161.819749ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:03.318397Z","caller":"traceutil/trace.go:171","msg":"trace[1345384395] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"253.671571ms","start":"2026-04-04T10:13:03.064693Z","end":"2026-04-04T10:13:03.318365Z","steps":["trace[1345384395] 'process raft request' (duration: 233.048172ms)","trace[1345384395] 'compare' (duration: 19.930979ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:13:03.318842Z","caller":"traceutil/trace.go:171","msg":"trace[1200912708] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"250.525266ms","start":"2026-04-04T10:13:03.068267Z","end":"2026-04-04T10:13:03.318792Z","steps":["trace[1200912708] 'process raft request' (duration: 250.037449ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:03.318888Z","caller":"traceutil/trace.go:171","msg":"trace[395725194] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1935; }","duration":"224.645545ms","start":"2026-04-04T10:13:03.094176Z","end":"2026-04-04T10:13:03.318822Z","steps":["trace[395725194] 'read index received' (duration: 118.521864ms)","trace[395725194] 'applied index is now lower than readState.Index' (duration: 106.121171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:13:03.319094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.874658ms","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-04-04T10:13:03.319182Z","caller":"traceutil/trace.go:171","msg":"trace[1291833676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1871; }","duration":"225.0128ms","start":"2026-04-04T10:13:03.094127Z","end":"2026-04-04T10:13:03.319139Z","steps":["trace[1291833676] 'agreement among raft nodes before linearized reading' (duration: 224.837558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:03.626835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.949037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:13:03.62691Z","caller":"traceutil/trace.go:171","msg":"trace[1795644838] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1873; }","duration":"156.119379ms","start":"2026-04-04T10:13:03.470774Z","end":"2026-04-04T10:13:03.626894Z","steps":["trace[1795644838] 'count revisions from in-memory index tree' (duration: 155.779775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:03.627306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.193431ms","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-04-04T10:13:03.627707Z","caller":"traceutil/trace.go:171","msg":"trace[1251582737] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"164.654228ms","start":"2026-04-04T10:13:03.463027Z","end":"2026-04-04T10:13:03.627681Z","steps":["trace[1251582737] 'range keys from in-memory index tree' (duration: 163.683804ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:16.284669Z","caller":"traceutil/trace.go:171","msg":"trace[1250908890] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"146.118976ms","start":"2026-04-04T10:13:16.138522Z","end":"2026-04-04T10:13:16.284641Z","steps":["trace[1250908890] 'process raft request' (duration: 145.940184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:32.104227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.258807ms","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-04-04T10:13:32.104337Z","caller":"traceutil/trace.go:171","msg":"trace[444012779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2084; }","duration":"103.387789ms","start":"2026-04-04T10:13:32.000926Z","end":"2026-04-04T10:13:32.104313Z","steps":["trace[444012779] 'range keys from in-memory index tree' (duration: 103.054314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:37.825734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.407689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:13:37.826004Z","caller":"traceutil/trace.go:171","msg":"trace[402394905] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"172.312544ms","start":"2026-04-04T10:13:37.653665Z","end":"2026-04-04T10:13:37.825978Z","steps":["trace[402394905] 'process raft request' (duration: 172.213073ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:37.826514Z","caller":"traceutil/trace.go:171","msg":"trace[579616909] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"174.285991ms","start":"2026-04-04T10:13:37.652207Z","end":"2026-04-04T10:13:37.826493Z","steps":["trace[579616909] 'process raft request' (duration: 19.027081ms)","trace[579616909] 'compare' (duration: 153.337045ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:13:37.841136Z","caller":"traceutil/trace.go:171","msg":"trace[682821746] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"140.473305ms","start":"2026-04-04T10:13:37.700635Z","end":"2026-04-04T10:13:37.841108Z","steps":["trace[682821746] 'process raft request' (duration: 140.316233ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:38.038171Z","caller":"traceutil/trace.go:171","msg":"trace[163971088] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"118.383786ms","start":"2026-04-04T10:13:37.919729Z","end":"2026-04-04T10:13:38.038112Z","steps":["trace[163971088] 'process raft request' (duration: 77.893224ms)","trace[163971088] 'compare' (duration: 40.182478ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:13:38.745352Z","caller":"traceutil/trace.go:171","msg":"trace[1343421503] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"248.134916ms","start":"2026-04-04T10:13:38.497183Z","end":"2026-04-04T10:13:38.745318Z","steps":["trace[1343421503] 'read index received' (duration: 248.016855ms)","trace[1343421503] 'applied index is now lower than readState.Index' (duration: 116.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:13:38.745657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.45948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-04T10:13:38.745478Z","caller":"traceutil/trace.go:171","msg":"trace[463929270] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"267.820435ms","start":"2026-04-04T10:13:38.477616Z","end":"2026-04-04T10:13:38.745436Z","steps":["trace[463929270] 'process raft request' (duration: 267.518951ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:38.745711Z","caller":"traceutil/trace.go:171","msg":"trace[1003524222] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:2121; }","duration":"248.554611ms","start":"2026-04-04T10:13:38.497141Z","end":"2026-04-04T10:13:38.745696Z","steps":["trace[1003524222] 'agreement among raft nodes before linearized reading' (duration: 248.310768ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:38.782737Z","caller":"traceutil/trace.go:171","msg":"trace[813866539] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"273.141365ms","start":"2026-04-04T10:13:38.50956Z","end":"2026-04-04T10:13:38.782701Z","steps":["trace[813866539] 'process raft request' (duration: 272.950662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:13:38.937001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.059245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-04T10:13:38.937126Z","caller":"traceutil/trace.go:171","msg":"trace[1911085637] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2123; }","duration":"110.252608ms","start":"2026-04-04T10:13:38.826848Z","end":"2026-04-04T10:13:38.9371Z","steps":["trace[1911085637] 'range keys from in-memory index tree' (duration: 109.937214ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:13:39.10425Z","caller":"traceutil/trace.go:171","msg":"trace[1610628357] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"158.914483ms","start":"2026-04-04T10:13:38.945216Z","end":"2026-04-04T10:13:39.10413Z","steps":["trace[1610628357] 'process raft request' (duration: 158.610959ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:07.924109Z","caller":"traceutil/trace.go:171","msg":"trace[551900572] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"144.763519ms","start":"2026-04-04T10:14:07.779297Z","end":"2026-04-04T10:14:07.924061Z","steps":["trace[551900572] 'process raft request' (duration: 114.999212ms)","trace[551900572] 'compare' (duration: 29.230661ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:14:07.924227Z","caller":"traceutil/trace.go:171","msg":"trace[961188605] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"141.764081ms","start":"2026-04-04T10:14:07.782453Z","end":"2026-04-04T10:14:07.924217Z","steps":["trace[961188605] 'process raft request' (duration: 141.530018ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:07.924155Z","caller":"traceutil/trace.go:171","msg":"trace[315630808] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"144.745489ms","start":"2026-04-04T10:14:07.779377Z","end":"2026-04-04T10:14:07.924122Z","steps":["trace[315630808] 'process raft request' (duration: 144.493386ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:14:07.924892Z","caller":"traceutil/trace.go:171","msg":"trace[1629007948] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"136.821658ms","start":"2026-04-04T10:14:07.788056Z","end":"2026-04-04T10:14:07.924877Z","steps":["trace[1629007948] 'process raft request' (duration: 135.995848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:21.60484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.277906ms","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-04-04T10:15:21.604965Z","caller":"traceutil/trace.go:171","msg":"trace[322695633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2780; }","duration":"229.496044ms","start":"2026-04-04T10:15:21.375441Z","end":"2026-04-04T10:15:21.604937Z","steps":["trace[322695633] 'range keys from in-memory index tree' (duration: 229.029866ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:20.049846Z","caller":"traceutil/trace.go:171","msg":"trace[246777323] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"118.504255ms","start":"2026-04-04T10:19:19.931316Z","end":"2026-04-04T10:19:20.04982Z","steps":["trace[246777323] 'process raft request' (duration: 69.810231ms)","trace[246777323] 'compare' (duration: 48.4594ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:32.084895Z","caller":"traceutil/trace.go:171","msg":"trace[1331480955] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"104.844435ms","start":"2026-04-04T10:19:31.980019Z","end":"2026-04-04T10:19:32.084863Z","steps":["trace[1331480955] 'process raft request' (duration: 103.109321ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.259702Z","caller":"traceutil/trace.go:171","msg":"trace[2030643336] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5237; }","duration":"365.756375ms","start":"2026-04-04T10:19:46.893908Z","end":"2026-04-04T10:19:47.259665Z","steps":["trace[2030643336] 'read index received' (duration: 318.640673ms)","trace[2030643336] 'applied index is now lower than readState.Index' (duration: 47.114082ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:47.260247Z","caller":"traceutil/trace.go:171","msg":"trace[625507250] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"558.299051ms","start":"2026-04-04T10:19:46.701864Z","end":"2026-04-04T10:19:47.260163Z","steps":["trace[625507250] 'process raft request' (duration: 510.732519ms)","trace[625507250] 'compare' (duration: 46.897658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:47.260407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:46.701812Z","time spent":"558.485714ms","remote":"127.0.0.1:45436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:19:47.260607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.621195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-04T10:19:47.260733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.807705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:19:47.260804Z","caller":"traceutil/trace.go:171","msg":"trace[1068070631] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5051; }","duration":"366.946638ms","start":"2026-04-04T10:19:46.893839Z","end":"2026-04-04T10:19:47.260786Z","steps":["trace[1068070631] 'agreement among raft nodes before linearized reading' (duration: 366.811396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:47.260866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:19:46.893811Z","time spent":"367.04737ms","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-04T10:19:47.261078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.198634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-04T10:19:47.261148Z","caller":"traceutil/trace.go:171","msg":"trace[1659774404] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5051; }","duration":"124.354637ms","start":"2026-04-04T10:19:47.136777Z","end":"2026-04-04T10:19:47.261132Z","steps":["trace[1659774404] 'agreement among raft nodes before linearized reading' (duration: 124.191873ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.261254Z","caller":"traceutil/trace.go:171","msg":"trace[420915710] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5051; }","duration":"247.838669ms","start":"2026-04-04T10:19:47.012869Z","end":"2026-04-04T10:19:47.260708Z","steps":["trace[420915710] 'agreement among raft nodes before linearized reading' (duration: 247.446291ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.434532Z","caller":"traceutil/trace.go:171","msg":"trace[745598461] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"160.973082ms","start":"2026-04-04T10:19:47.273494Z","end":"2026-04-04T10:19:47.434467Z","steps":["trace[745598461] 'process raft request' (duration: 111.465974ms)","trace[745598461] 'compare' (duration: 48.689853ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:47.726382Z","caller":"traceutil/trace.go:171","msg":"trace[613756681] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"282.76171ms","start":"2026-04-04T10:19:47.443586Z","end":"2026-04-04T10:19:47.726348Z","steps":["trace[613756681] 'process raft request' (duration: 282.698299ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.726466Z","caller":"traceutil/trace.go:171","msg":"trace[91466724] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"282.892313ms","start":"2026-04-04T10:19:47.44355Z","end":"2026-04-04T10:19:47.726442Z","steps":["trace[91466724] 'process raft request' (duration: 282.629298ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.726454Z","caller":"traceutil/trace.go:171","msg":"trace[1236672768] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"282.845942ms","start":"2026-04-04T10:19:47.443551Z","end":"2026-04-04T10:19:47.726397Z","steps":["trace[1236672768] 'process raft request' (duration: 225.549887ms)","trace[1236672768] 'compare' (duration: 56.026802ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:47.745233Z","caller":"traceutil/trace.go:171","msg":"trace[1508835371] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5241; }","duration":"184.702211ms","start":"2026-04-04T10:19:47.560504Z","end":"2026-04-04T10:19:47.745207Z","steps":["trace[1508835371] 'read index received' (duration: 108.619299ms)","trace[1508835371] 'applied index is now lower than readState.Index' (duration: 76.081782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:47.745361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.835593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:19:47.745395Z","caller":"traceutil/trace.go:171","msg":"trace[1128422391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5058; }","duration":"184.924895ms","start":"2026-04-04T10:19:47.560459Z","end":"2026-04-04T10:19:47.745384Z","steps":["trace[1128422391] 'agreement among raft nodes before linearized reading' (duration: 184.847844ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:19:47.745568Z","caller":"traceutil/trace.go:171","msg":"trace[332229777] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"198.3628ms","start":"2026-04-04T10:19:47.547185Z","end":"2026-04-04T10:19:47.745548Z","steps":["trace[332229777] 'process raft request' (duration: 197.865181ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:20:44.88843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-04-04T10:20:44.965009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"75.148169ms","hash":1428456891,"current-db-size-bytes":51351552,"current-db-size":"51 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-04T10:20:44.965084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1428456891,"revision":2904,"compact-revision":-1} {"level":"warn","ts":"2026-04-04T10:23:56.96174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.505961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:23:56.961927Z","caller":"traceutil/trace.go:171","msg":"trace[1092562727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6852; }","duration":"186.832275ms","start":"2026-04-04T10:23:56.775074Z","end":"2026-04-04T10:23:56.961906Z","steps":["trace[1092562727] 'count revisions from in-memory index tree' (duration: 186.397777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:56.963131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.880912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:437658"} {"level":"info","ts":"2026-04-04T10:23:56.96323Z","caller":"traceutil/trace.go:171","msg":"trace[1288369381] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6852; }","duration":"244.08514ms","start":"2026-04-04T10:23:56.719124Z","end":"2026-04-04T10:23:56.963209Z","steps":["trace[1288369381] 'range keys from in-memory index tree' (duration: 243.380329ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:57.478972Z","caller":"traceutil/trace.go:171","msg":"trace[666777150] linearizableReadLoop","detail":"{readStateIndex:7104; appliedIndex:7103; }","duration":"101.653439ms","start":"2026-04-04T10:23:57.377298Z","end":"2026-04-04T10:23:57.478951Z","steps":["trace[666777150] 'read index received' (duration: 77.505007ms)","trace[666777150] 'applied index is now lower than readState.Index' (duration: 24.147532ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:57.479183Z","caller":"traceutil/trace.go:171","msg":"trace[978492970] transaction","detail":"{read_only:false; response_revision:6856; number_of_response:1; }","duration":"141.183962ms","start":"2026-04-04T10:23:57.337985Z","end":"2026-04-04T10:23:57.479169Z","steps":["trace[978492970] 'process raft request' (duration: 116.803811ms)","trace[978492970] 'compare' (duration: 23.787398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:23:57.479205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.789394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:23:57.479241Z","caller":"traceutil/trace.go:171","msg":"trace[1504498721] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6856; }","duration":"101.990722ms","start":"2026-04-04T10:23:57.37724Z","end":"2026-04-04T10:23:57.479231Z","steps":["trace[1504498721] 'agreement among raft nodes before linearized reading' (duration: 101.785994ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:57.504462Z","caller":"traceutil/trace.go:171","msg":"trace[1610766489] transaction","detail":"{read_only:false; response_revision:6857; number_of_response:1; }","duration":"118.329001ms","start":"2026-04-04T10:23:57.386108Z","end":"2026-04-04T10:23:57.504437Z","steps":["trace[1610766489] 'process raft request' (duration: 117.688596ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:44.896842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5508} {"level":"info","ts":"2026-04-04T10:25:44.978361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5508,"took":"79.521729ms","hash":3128720412,"current-db-size-bytes":51351552,"current-db-size":"51 MB","current-db-size-in-use-bytes":18378752,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-04T10:25:44.978438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3128720412,"revision":5508,"compact-revision":2904}