{"level":"warn","ts":"2026-03-14T06:59:25.283531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T06:59:25.283609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-03-14T06:59:25.283666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T06:59:25.283675Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-14T06:59:25.283696Z","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-03-14T06:59:25.284274Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-03-14T06:59:25.284342Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-03-14T06:59:25.288008Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.459944ms"} {"level":"info","ts":"2026-03-14T06:59:25.295559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-03-14T06:59:25.295734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T06:59:25.295783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-03-14T06:59:25.295818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T06:59:25.295846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-03-14T06:59:25.295916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-03-14T06:59:25.300501Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T06:59:25.302466Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T06:59:25.304566Z","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-03-14T06:59:25.308665Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T06:59:25.310792Z","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-03-14T06:59:25.310897Z","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-03-14T06:59:25.310917Z","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-03-14T06:59:25.310189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T06:59:25.316147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-03-14T06:59:25.316258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-14T06:59:25.320799Z","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-03-14T06:59:25.321048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-14T06:59:25.321073Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-14T06:59:25.321254Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T06:59:25.321289Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T06:59:26.196447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T06:59:26.196509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T06:59:26.19658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-03-14T06:59:26.196601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-03-14T06:59:26.196615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-14T06:59:26.196633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-03-14T06:59:26.196654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-14T06:59:26.20108Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T06:59:26.201072Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:59:26.201112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T06:59:26.201162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T06:59:26.20145Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T06:59:26.201576Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T06:59:26.203768Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:59:26.203904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:59:26.203952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:59:26.204732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T06:59:26.205814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"warn","ts":"2026-03-14T06:59:27.759948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.647761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T06:59:27.760047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.672454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T06:59:27.760081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.517092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T06:59:27.76009Z","caller":"traceutil/trace.go:171","msg":"trace[103011623] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:0; response_revision:3; }","duration":"214.706365ms","start":"2026-03-14T06:59:27.545367Z","end":"2026-03-14T06:59:27.760073Z","steps":["trace[103011623] 'range keys from in-memory index tree' (duration: 214.597391ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.760102Z","caller":"traceutil/trace.go:171","msg":"trace[627015004] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"228.564933ms","start":"2026-03-14T06:59:27.531529Z","end":"2026-03-14T06:59:27.760094Z","steps":["trace[627015004] 'range keys from in-memory index tree' (duration: 228.433049ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.760045Z","caller":"traceutil/trace.go:171","msg":"trace[1094699338] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"245.766893ms","start":"2026-03-14T06:59:27.514264Z","end":"2026-03-14T06:59:27.760031Z","steps":["trace[1094699338] 'range keys from in-memory index tree' (duration: 245.537317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:59:27.76022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.424519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T06:59:27.760272Z","caller":"traceutil/trace.go:171","msg":"trace[1758035939] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"213.552865ms","start":"2026-03-14T06:59:27.546708Z","end":"2026-03-14T06:59:27.760261Z","steps":["trace[1758035939] 'range keys from in-memory index tree' (duration: 213.340187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:59:27.760386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.044013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T06:59:27.760475Z","caller":"traceutil/trace.go:171","msg":"trace[1570023517] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"246.181026ms","start":"2026-03-14T06:59:27.514273Z","end":"2026-03-14T06:59:27.760454Z","steps":["trace[1570023517] 'range keys from in-memory index tree' (duration: 245.912418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:59:27.759949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.805988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T06:59:27.760593Z","caller":"traceutil/trace.go:171","msg":"trace[1092561471] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"245.503928ms","start":"2026-03-14T06:59:27.515071Z","end":"2026-03-14T06:59:27.760575Z","steps":["trace[1092561471] 'range keys from in-memory index tree' (duration: 244.722436ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.760116Z","caller":"traceutil/trace.go:171","msg":"trace[1132837290] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"212.572536ms","start":"2026-03-14T06:59:27.547513Z","end":"2026-03-14T06:59:27.760086Z","steps":["trace[1132837290] 'process raft request' (duration: 146.029126ms)","trace[1132837290] 'compare' (duration: 66.323364ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:59:27.76949Z","caller":"traceutil/trace.go:171","msg":"trace[566233429] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"221.006237ms","start":"2026-03-14T06:59:27.548468Z","end":"2026-03-14T06:59:27.769475Z","steps":["trace[566233429] 'process raft request' (duration: 220.805242ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769527Z","caller":"traceutil/trace.go:171","msg":"trace[80358438] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"220.705899ms","start":"2026-03-14T06:59:27.548806Z","end":"2026-03-14T06:59:27.769512Z","steps":["trace[80358438] 'process raft request' (duration: 220.570886ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.76963Z","caller":"traceutil/trace.go:171","msg":"trace[41032686] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"220.74565ms","start":"2026-03-14T06:59:27.548863Z","end":"2026-03-14T06:59:27.769608Z","steps":["trace[41032686] 'process raft request' (duration: 220.585166ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769685Z","caller":"traceutil/trace.go:171","msg":"trace[1555002873] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"212.578177ms","start":"2026-03-14T06:59:27.557097Z","end":"2026-03-14T06:59:27.769675Z","steps":["trace[1555002873] 'process raft request' (duration: 212.421203ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769695Z","caller":"traceutil/trace.go:171","msg":"trace[105685301] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"210.637833ms","start":"2026-03-14T06:59:27.559014Z","end":"2026-03-14T06:59:27.769652Z","steps":["trace[105685301] 'process raft request' (duration: 210.542021ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769737Z","caller":"traceutil/trace.go:171","msg":"trace[1010610450] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"220.936325ms","start":"2026-03-14T06:59:27.548761Z","end":"2026-03-14T06:59:27.769697Z","steps":["trace[1010610450] 'process raft request' (duration: 220.653648ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769757Z","caller":"traceutil/trace.go:171","msg":"trace[1812239285] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"220.561635ms","start":"2026-03-14T06:59:27.549189Z","end":"2026-03-14T06:59:27.769751Z","steps":["trace[1812239285] 'process raft request' (duration: 220.302368ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:27.769616Z","caller":"traceutil/trace.go:171","msg":"trace[1488026091] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:6; }","duration":"220.307509ms","start":"2026-03-14T06:59:27.549284Z","end":"2026-03-14T06:59:27.769592Z","steps":["trace[1488026091] 'read index received' (duration: 144.3345ms)","trace[1488026091] 'applied index is now lower than readState.Index' (duration: 75.971999ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:59:27.769861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.577096ms","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-03-14T06:59:27.769888Z","caller":"traceutil/trace.go:171","msg":"trace[974980446] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"220.640057ms","start":"2026-03-14T06:59:27.549241Z","end":"2026-03-14T06:59:27.769881Z","steps":["trace[974980446] 'agreement among raft nodes before linearized reading' (duration: 220.569396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:59:27.774178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.656066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-14T06:59:27.774254Z","caller":"traceutil/trace.go:171","msg":"trace[1024810504] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"175.749779ms","start":"2026-03-14T06:59:27.598486Z","end":"2026-03-14T06:59:27.774236Z","steps":["trace[1024810504] 'agreement among raft nodes before linearized reading' (duration: 175.591004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:59:40.401487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.169227ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:59:40.401669Z","caller":"traceutil/trace.go:171","msg":"trace[1356441229] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:363; }","duration":"181.371143ms","start":"2026-03-14T06:59:40.220278Z","end":"2026-03-14T06:59:40.40165Z","steps":["trace[1356441229] 'range keys from in-memory index tree' (duration: 181.101266ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:59:40.680081Z","caller":"traceutil/trace.go:171","msg":"trace[210379411] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"266.224194ms","start":"2026-03-14T06:59:40.413826Z","end":"2026-03-14T06:59:40.68005Z","steps":["trace[210379411] 'process raft request' (duration: 265.928386ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:01.65886Z","caller":"traceutil/trace.go:171","msg":"trace[1469761033] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"243.884803ms","start":"2026-03-14T07:00:01.414954Z","end":"2026-03-14T07:00:01.658838Z","steps":["trace[1469761033] 'process raft request' (duration: 243.728389ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:01.674339Z","caller":"traceutil/trace.go:171","msg":"trace[1554920990] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"189.394473ms","start":"2026-03-14T07:00:01.484906Z","end":"2026-03-14T07:00:01.674301Z","steps":["trace[1554920990] 'process raft request' (duration: 189.126955ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:01.867218Z","caller":"traceutil/trace.go:171","msg":"trace[632285145] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"187.873902ms","start":"2026-03-14T07:00:01.679318Z","end":"2026-03-14T07:00:01.867192Z","steps":["trace[632285145] 'process raft request' (duration: 187.724357ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:01.880488Z","caller":"traceutil/trace.go:171","msg":"trace[1010362865] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"200.65327ms","start":"2026-03-14T07:00:01.679812Z","end":"2026-03-14T07:00:01.880465Z","steps":["trace[1010362865] 'process raft request' (duration: 200.539527ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:11.470464Z","caller":"traceutil/trace.go:171","msg":"trace[255790683] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"334.02684ms","start":"2026-03-14T07:01:11.1364Z","end":"2026-03-14T07:01:11.470427Z","steps":["trace[255790683] 'process raft request' (duration: 256.883123ms)","trace[255790683] 'compare' (duration: 76.823607ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:11.470706Z","caller":"traceutil/trace.go:171","msg":"trace[105464262] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"323.206666ms","start":"2026-03-14T07:01:11.147474Z","end":"2026-03-14T07:01:11.47068Z","steps":["trace[105464262] 'process raft request' (duration: 322.804315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:11.471127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:11.147451Z","time spent":"323.32639ms","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:11.471117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:11.136383Z","time spent":"334.193424ms","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:11.857178Z","caller":"traceutil/trace.go:171","msg":"trace[570621557] linearizableReadLoop","detail":"{readStateIndex:1610; appliedIndex:1609; }","duration":"178.256416ms","start":"2026-03-14T07:01:11.67888Z","end":"2026-03-14T07:01:11.857136Z","steps":["trace[570621557] 'read index received' (duration: 163.215263ms)","trace[570621557] 'applied index is now lower than readState.Index' (duration: 15.039503ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:11.85727Z","caller":"traceutil/trace.go:171","msg":"trace[750997480] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"314.1194ms","start":"2026-03-14T07:01:11.543115Z","end":"2026-03-14T07:01:11.857235Z","steps":["trace[750997480] 'process raft request' (duration: 299.078957ms)","trace[750997480] 'compare' (duration: 14.61136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:11.857378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.485832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:01:11.857415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:11.543097Z","time spent":"314.243404ms","remote":"127.0.0.1:57636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:11.857433Z","caller":"traceutil/trace.go:171","msg":"trace[280395305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"178.588345ms","start":"2026-03-14T07:01:11.678828Z","end":"2026-03-14T07:01:11.857416Z","steps":["trace[280395305] 'agreement among raft nodes before linearized reading' (duration: 178.487712ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:25.639344Z","caller":"traceutil/trace.go:171","msg":"trace[731415045] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"123.593922ms","start":"2026-03-14T07:01:25.515718Z","end":"2026-03-14T07:01:25.639312Z","steps":["trace[731415045] 'process raft request' (duration: 123.361796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:50.422234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.267097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-14T07:01:50.422489Z","caller":"traceutil/trace.go:171","msg":"trace[1314235069] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1795; }","duration":"100.537814ms","start":"2026-03-14T07:01:50.321905Z","end":"2026-03-14T07:01:50.422443Z","steps":["trace[1314235069] 'range keys from in-memory index tree' (duration: 100.112673ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:50.568321Z","caller":"traceutil/trace.go:171","msg":"trace[1649472767] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"140.228069ms","start":"2026-03-14T07:01:50.428055Z","end":"2026-03-14T07:01:50.568284Z","steps":["trace[1649472767] 'process raft request' (duration: 109.837905ms)","trace[1649472767] 'compare' (duration: 30.160978ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:50.460644Z","caller":"traceutil/trace.go:171","msg":"trace[886415521] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"122.444477ms","start":"2026-03-14T07:02:50.338173Z","end":"2026-03-14T07:02:50.460617Z","steps":["trace[886415521] 'process raft request' (duration: 122.300413ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:15.567688Z","caller":"traceutil/trace.go:171","msg":"trace[1204966432] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"208.205813ms","start":"2026-03-14T07:03:15.359457Z","end":"2026-03-14T07:03:15.567663Z","steps":["trace[1204966432] 'process raft request' (duration: 208.055719ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:15.568824Z","caller":"traceutil/trace.go:171","msg":"trace[1286624931] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"157.627125ms","start":"2026-03-14T07:03:15.411188Z","end":"2026-03-14T07:03:15.568815Z","steps":["trace[1286624931] 'process raft request' (duration: 157.595554ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:15.568888Z","caller":"traceutil/trace.go:171","msg":"trace[390495199] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"205.564529ms","start":"2026-03-14T07:03:15.363278Z","end":"2026-03-14T07:03:15.568843Z","steps":["trace[390495199] 'process raft request' (duration: 205.464076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:24.790651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.211109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-14T07:03:24.790869Z","caller":"traceutil/trace.go:171","msg":"trace[455222119] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2548; }","duration":"166.502993ms","start":"2026-03-14T07:03:24.624348Z","end":"2026-03-14T07:03:24.790851Z","steps":["trace[455222119] 'read index received' (duration: 29.956834ms)","trace[455222119] 'applied index is now lower than readState.Index' (duration: 136.544389ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:24.791023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.889141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:24.791104Z","caller":"traceutil/trace.go:171","msg":"trace[1913908107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2450; }","duration":"113.014385ms","start":"2026-03-14T07:03:24.678077Z","end":"2026-03-14T07:03:24.791092Z","steps":["trace[1913908107] 'agreement among raft nodes before linearized reading' (duration: 112.901611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:24.791139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.769261ms","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-03-14T07:03:24.791246Z","caller":"traceutil/trace.go:171","msg":"trace[1457243684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2450; }","duration":"166.920945ms","start":"2026-03-14T07:03:24.624305Z","end":"2026-03-14T07:03:24.791226Z","steps":["trace[1457243684] 'agreement among raft nodes before linearized reading' (duration: 166.601725ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:24.962097Z","caller":"traceutil/trace.go:171","msg":"trace[24635223] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"167.369237ms","start":"2026-03-14T07:03:24.794636Z","end":"2026-03-14T07:03:24.962005Z","steps":["trace[24635223] 'process raft request' (duration: 167.14575ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:24.975457Z","caller":"traceutil/trace.go:171","msg":"trace[1579208475] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"180.050869ms","start":"2026-03-14T07:03:24.795371Z","end":"2026-03-14T07:03:24.975422Z","steps":["trace[1579208475] 'process raft request' (duration: 179.829053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:15.85599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.629261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-03-14T07:07:15.856071Z","caller":"traceutil/trace.go:171","msg":"trace[1892647742] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4445; }","duration":"131.738364ms","start":"2026-03-14T07:07:15.724304Z","end":"2026-03-14T07:07:15.856042Z","steps":["trace[1892647742] 'range keys from in-memory index tree' (duration: 131.440495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:15.856038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.946089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7np6j\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-14T07:07:15.856075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.559853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:15.856152Z","caller":"traceutil/trace.go:171","msg":"trace[1373033168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4445; }","duration":"175.681576ms","start":"2026-03-14T07:07:15.68045Z","end":"2026-03-14T07:07:15.856132Z","steps":["trace[1373033168] 'range keys from in-memory index tree' (duration: 175.410099ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:15.856147Z","caller":"traceutil/trace.go:171","msg":"trace[920953520] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7np6j; range_end:; response_count:1; response_revision:4445; }","duration":"158.106964ms","start":"2026-03-14T07:07:15.698013Z","end":"2026-03-14T07:07:15.85612Z","steps":["trace[920953520] 'range keys from in-memory index tree' (duration: 157.743683ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:25.789985Z","caller":"traceutil/trace.go:171","msg":"trace[1611797300] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4834; }","duration":"111.584889ms","start":"2026-03-14T07:07:25.678369Z","end":"2026-03-14T07:07:25.789954Z","steps":["trace[1611797300] 'read index received' (duration: 111.287421ms)","trace[1611797300] 'applied index is now lower than readState.Index' (duration: 296.138µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:25.790053Z","caller":"traceutil/trace.go:171","msg":"trace[772960587] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"134.80047ms","start":"2026-03-14T07:07:25.655217Z","end":"2026-03-14T07:07:25.790017Z","steps":["trace[772960587] 'process raft request' (duration: 134.482991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:25.790163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.773765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:25.790208Z","caller":"traceutil/trace.go:171","msg":"trace[1414302405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4648; }","duration":"111.862677ms","start":"2026-03-14T07:07:25.678332Z","end":"2026-03-14T07:07:25.790195Z","steps":["trace[1414302405] 'agreement among raft nodes before linearized reading' (duration: 111.745413ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:38.586361Z","caller":"traceutil/trace.go:171","msg":"trace[76428868] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5013; }","duration":"106.530947ms","start":"2026-03-14T07:07:38.479802Z","end":"2026-03-14T07:07:38.586333Z","steps":["trace[76428868] 'read index received' (duration: 30.344091ms)","trace[76428868] 'applied index is now lower than readState.Index' (duration: 76.185276ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:38.586517Z","caller":"traceutil/trace.go:171","msg":"trace[339479162] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"151.542879ms","start":"2026-03-14T07:07:38.434933Z","end":"2026-03-14T07:07:38.586476Z","steps":["trace[339479162] 'process raft request' (duration: 75.233549ms)","trace[339479162] 'compare' (duration: 76.047903ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:38.586608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.782004ms","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-03-14T07:07:38.586703Z","caller":"traceutil/trace.go:171","msg":"trace[793404340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4825; }","duration":"106.926588ms","start":"2026-03-14T07:07:38.47976Z","end":"2026-03-14T07:07:38.586687Z","steps":["trace[793404340] 'agreement among raft nodes before linearized reading' (duration: 106.65703ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:53.197142Z","caller":"traceutil/trace.go:171","msg":"trace[1523463869] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"228.835083ms","start":"2026-03-14T07:07:52.968269Z","end":"2026-03-14T07:07:53.197104Z","steps":["trace[1523463869] 'read index received' (duration: 228.604187ms)","trace[1523463869] 'applied index is now lower than readState.Index' (duration: 230.036µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:53.1972Z","caller":"traceutil/trace.go:171","msg":"trace[949309774] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"233.374288ms","start":"2026-03-14T07:07:52.963783Z","end":"2026-03-14T07:07:53.197157Z","steps":["trace[949309774] 'process raft request' (duration: 233.173793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:53.19734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.060799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-14T07:07:53.197378Z","caller":"traceutil/trace.go:171","msg":"trace[1074858463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5036; }","duration":"229.147091ms","start":"2026-03-14T07:07:52.968222Z","end":"2026-03-14T07:07:53.197369Z","steps":["trace[1074858463] 'agreement among raft nodes before linearized reading' (duration: 228.996807ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:53.481017Z","caller":"traceutil/trace.go:171","msg":"trace[2010170858] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"140.672714ms","start":"2026-03-14T07:07:53.340309Z","end":"2026-03-14T07:07:53.480982Z","steps":["trace[2010170858] 'process raft request' (duration: 134.997867ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:09:26.231415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2754} {"level":"info","ts":"2026-03-14T07:09:26.29209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2754,"took":"59.190362ms","hash":2901910012,"current-db-size-bytes":41730048,"current-db-size":"42 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-14T07:09:26.292175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2901910012,"revision":2754,"compact-revision":-1} {"level":"info","ts":"2026-03-14T07:13:52.315135Z","caller":"traceutil/trace.go:171","msg":"trace[2084843996] linearizableReadLoop","detail":"{readStateIndex:7853; appliedIndex:7852; }","duration":"182.630805ms","start":"2026-03-14T07:13:52.132481Z","end":"2026-03-14T07:13:52.315112Z","steps":["trace[2084843996] 'read index received' (duration: 182.428259ms)","trace[2084843996] 'applied index is now lower than readState.Index' (duration: 201.486µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:13:52.315279Z","caller":"traceutil/trace.go:171","msg":"trace[1383607794] transaction","detail":"{read_only:false; response_revision:7571; number_of_response:1; }","duration":"212.262299ms","start":"2026-03-14T07:13:52.10297Z","end":"2026-03-14T07:13:52.315232Z","steps":["trace[1383607794] 'process raft request' (duration: 211.991031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:13:52.315388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.893194ms","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-03-14T07:13:52.315449Z","caller":"traceutil/trace.go:171","msg":"trace[989999872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7571; }","duration":"183.007757ms","start":"2026-03-14T07:13:52.132426Z","end":"2026-03-14T07:13:52.315434Z","steps":["trace[989999872] 'agreement among raft nodes before linearized reading' (duration: 182.835772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:13:52.315519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.665858ms","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-03-14T07:13:52.315586Z","caller":"traceutil/trace.go:171","msg":"trace[1216438522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7571; }","duration":"174.767561ms","start":"2026-03-14T07:13:52.140802Z","end":"2026-03-14T07:13:52.315569Z","steps":["trace[1216438522] 'agreement among raft nodes before linearized reading' (duration: 174.640567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:13:52.315725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.691568ms","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-03-14T07:13:52.315813Z","caller":"traceutil/trace.go:171","msg":"trace[993495058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7571; }","duration":"119.860483ms","start":"2026-03-14T07:13:52.195933Z","end":"2026-03-14T07:13:52.315794Z","steps":["trace[993495058] 'agreement among raft nodes before linearized reading' (duration: 119.598246ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:13:52.490078Z","caller":"traceutil/trace.go:171","msg":"trace[71433488] transaction","detail":"{read_only:false; response_revision:7573; number_of_response:1; }","duration":"170.529865ms","start":"2026-03-14T07:13:52.319527Z","end":"2026-03-14T07:13:52.490057Z","steps":["trace[71433488] 'process raft request' (duration: 170.459313ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:13:52.490253Z","caller":"traceutil/trace.go:171","msg":"trace[1906102098] linearizableReadLoop","detail":"{readStateIndex:7856; appliedIndex:7853; }","duration":"121.262976ms","start":"2026-03-14T07:13:52.368973Z","end":"2026-03-14T07:13:52.490236Z","steps":["trace[1906102098] 'read index received' (duration: 107.601269ms)","trace[1906102098] 'applied index is now lower than readState.Index' (duration: 13.660197ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:13:52.490403Z","caller":"traceutil/trace.go:171","msg":"trace[1484424234] transaction","detail":"{read_only:false; response_revision:7572; number_of_response:1; }","duration":"171.055951ms","start":"2026-03-14T07:13:52.319327Z","end":"2026-03-14T07:13:52.490382Z","steps":["trace[1484424234] 'process raft request' (duration: 157.243619ms)","trace[1484424234] 'compare' (duration: 13.303997ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:13:52.490474Z","caller":"traceutil/trace.go:171","msg":"trace[1553287940] transaction","detail":"{read_only:false; response_revision:7574; number_of_response:1; }","duration":"170.870915ms","start":"2026-03-14T07:13:52.319591Z","end":"2026-03-14T07:13:52.490462Z","steps":["trace[1553287940] 'process raft request' (duration: 170.436432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:13:52.490694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.691399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-03-14T07:13:52.490738Z","caller":"traceutil/trace.go:171","msg":"trace[909262818] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7574; }","duration":"121.827744ms","start":"2026-03-14T07:13:52.368896Z","end":"2026-03-14T07:13:52.490724Z","steps":["trace[909262818] 'agreement among raft nodes before linearized reading' (duration: 121.661599ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:04.724492Z","caller":"traceutil/trace.go:171","msg":"trace[1502357300] linearizableReadLoop","detail":"{readStateIndex:7915; appliedIndex:7914; }","duration":"177.96043ms","start":"2026-03-14T07:14:04.546503Z","end":"2026-03-14T07:14:04.724464Z","steps":["trace[1502357300] 'read index received' (duration: 134.979668ms)","trace[1502357300] 'applied index is now lower than readState.Index' (duration: 42.979562ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:04.724542Z","caller":"traceutil/trace.go:171","msg":"trace[1398377457] transaction","detail":"{read_only:false; response_revision:7629; number_of_response:1; }","duration":"187.112196ms","start":"2026-03-14T07:14:04.537386Z","end":"2026-03-14T07:14:04.724498Z","steps":["trace[1398377457] 'process raft request' (duration: 144.162685ms)","trace[1398377457] 'compare' (duration: 42.769226ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:14:04.724809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.560781ms","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-03-14T07:14:04.724921Z","caller":"traceutil/trace.go:171","msg":"trace[1435438441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7629; }","duration":"176.716435ms","start":"2026-03-14T07:14:04.548182Z","end":"2026-03-14T07:14:04.724899Z","steps":["trace[1435438441] 'agreement among raft nodes before linearized reading' (duration: 176.489849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:04.724925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.548222ms","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-03-14T07:14:04.724978Z","caller":"traceutil/trace.go:171","msg":"trace[1471858109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7629; }","duration":"170.654185ms","start":"2026-03-14T07:14:04.554309Z","end":"2026-03-14T07:14:04.724964Z","steps":["trace[1471858109] 'agreement among raft nodes before linearized reading' (duration: 170.45144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:04.725139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.05307ms","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-03-14T07:14:04.725225Z","caller":"traceutil/trace.go:171","msg":"trace[399746912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7629; }","duration":"169.165103ms","start":"2026-03-14T07:14:04.556042Z","end":"2026-03-14T07:14:04.725207Z","steps":["trace[399746912] 'agreement among raft nodes before linearized reading' (duration: 168.665199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:04.725166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.669771ms","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-03-14T07:14:04.72546Z","caller":"traceutil/trace.go:171","msg":"trace[816665143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7629; }","duration":"178.958298ms","start":"2026-03-14T07:14:04.546486Z","end":"2026-03-14T07:14:04.725444Z","steps":["trace[816665143] 'agreement among raft nodes before linearized reading' (duration: 178.119525ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:06.871346Z","caller":"traceutil/trace.go:171","msg":"trace[2082951396] linearizableReadLoop","detail":"{readStateIndex:7926; appliedIndex:7925; }","duration":"106.46025ms","start":"2026-03-14T07:14:06.764862Z","end":"2026-03-14T07:14:06.871322Z","steps":["trace[2082951396] 'read index received' (duration: 62.530661ms)","trace[2082951396] 'applied index is now lower than readState.Index' (duration: 43.928709ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:06.871417Z","caller":"traceutil/trace.go:171","msg":"trace[1093481682] transaction","detail":"{read_only:false; response_revision:7640; number_of_response:1; }","duration":"107.473627ms","start":"2026-03-14T07:14:06.763912Z","end":"2026-03-14T07:14:06.871385Z","steps":["trace[1093481682] 'process raft request' (duration: 63.481857ms)","trace[1093481682] 'compare' (duration: 43.815696ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:14:06.871507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.624484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:06.871562Z","caller":"traceutil/trace.go:171","msg":"trace[935953411] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:7640; }","duration":"106.725777ms","start":"2026-03-14T07:14:06.764824Z","end":"2026-03-14T07:14:06.87155Z","steps":["trace[935953411] 'agreement among raft nodes before linearized reading' (duration: 106.615854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:06.872635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.686754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:06.872799Z","caller":"traceutil/trace.go:171","msg":"trace[188929923] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:7641; }","duration":"107.89227ms","start":"2026-03-14T07:14:06.764886Z","end":"2026-03-14T07:14:06.872778Z","steps":["trace[188929923] 'agreement among raft nodes before linearized reading' (duration: 107.668754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:06.873341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.640812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:06.873379Z","caller":"traceutil/trace.go:171","msg":"trace[1705995061] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:7641; }","duration":"107.733854ms","start":"2026-03-14T07:14:06.765636Z","end":"2026-03-14T07:14:06.87337Z","steps":["trace[1705995061] 'agreement among raft nodes before linearized reading' (duration: 107.687293ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:26.238012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5768} {"level":"info","ts":"2026-03-14T07:14:26.323681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5768,"took":"83.734635ms","hash":4117421682,"current-db-size-bytes":42897408,"current-db-size":"43 MB","current-db-size-in-use-bytes":22970368,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-14T07:14:26.323749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4117421682,"revision":5768,"compact-revision":2754} {"level":"info","ts":"2026-03-14T07:14:36.7066Z","caller":"traceutil/trace.go:171","msg":"trace[2018930052] linearizableReadLoop","detail":"{readStateIndex:8133; appliedIndex:8132; }","duration":"132.785565ms","start":"2026-03-14T07:14:36.573788Z","end":"2026-03-14T07:14:36.706574Z","steps":["trace[2018930052] 'read index received' (duration: 132.636971ms)","trace[2018930052] 'applied index is now lower than readState.Index' (duration: 146.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:36.706792Z","caller":"traceutil/trace.go:171","msg":"trace[332609738] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"178.424355ms","start":"2026-03-14T07:14:36.528356Z","end":"2026-03-14T07:14:36.70678Z","steps":["trace[332609738] 'process raft request' (duration: 178.036743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:36.707069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.277281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-14T07:14:36.707111Z","caller":"traceutil/trace.go:171","msg":"trace[1825770772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7838; }","duration":"133.393053ms","start":"2026-03-14T07:14:36.573706Z","end":"2026-03-14T07:14:36.7071Z","steps":["trace[1825770772] 'agreement among raft nodes before linearized reading' (duration: 133.240939ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:46.916579Z","caller":"traceutil/trace.go:171","msg":"trace[1580793880] linearizableReadLoop","detail":"{readStateIndex:8232; appliedIndex:8231; }","duration":"144.45627ms","start":"2026-03-14T07:14:46.7721Z","end":"2026-03-14T07:14:46.916556Z","steps":["trace[1580793880] 'read index received' (duration: 144.301146ms)","trace[1580793880] 'applied index is now lower than readState.Index' (duration: 154.154µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:46.916622Z","caller":"traceutil/trace.go:171","msg":"trace[549022205] transaction","detail":"{read_only:false; response_revision:7933; number_of_response:1; }","duration":"147.139759ms","start":"2026-03-14T07:14:46.769454Z","end":"2026-03-14T07:14:46.916594Z","steps":["trace[549022205] 'process raft request' (duration: 146.926532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:46.916958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.838261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112159"} {"level":"info","ts":"2026-03-14T07:14:46.917005Z","caller":"traceutil/trace.go:171","msg":"trace[1871271411] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:7933; }","duration":"144.940444ms","start":"2026-03-14T07:14:46.772053Z","end":"2026-03-14T07:14:46.916993Z","steps":["trace[1871271411] 'agreement among raft nodes before linearized reading' (duration: 144.597904ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:46.935297Z","caller":"traceutil/trace.go:171","msg":"trace[242443333] transaction","detail":"{read_only:false; response_revision:7934; number_of_response:1; }","duration":"114.637828ms","start":"2026-03-14T07:14:46.820643Z","end":"2026-03-14T07:14:46.935281Z","steps":["trace[242443333] 'process raft request' (duration: 114.501534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:47.183559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.357561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:14:47.18373Z","caller":"traceutil/trace.go:171","msg":"trace[49791570] transaction","detail":"{read_only:false; response_revision:7937; number_of_response:1; }","duration":"171.701235ms","start":"2026-03-14T07:14:47.012012Z","end":"2026-03-14T07:14:47.183713Z","steps":["trace[49791570] 'process raft request' (duration: 171.637504ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:47.183935Z","caller":"traceutil/trace.go:171","msg":"trace[1827087108] transaction","detail":"{read_only:false; response_revision:7936; number_of_response:1; }","duration":"253.976759ms","start":"2026-03-14T07:14:46.929946Z","end":"2026-03-14T07:14:47.183922Z","steps":["trace[1827087108] 'process raft request' (duration: 120.174343ms)","trace[1827087108] 'compare' (duration: 133.231188ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:47.184003Z","caller":"traceutil/trace.go:171","msg":"trace[912814014] linearizableReadLoop","detail":"{readStateIndex:8235; appliedIndex:8234; }","duration":"234.208024ms","start":"2026-03-14T07:14:46.94975Z","end":"2026-03-14T07:14:47.183958Z","steps":["trace[912814014] 'read index received' (duration: 100.310795ms)","trace[912814014] 'applied index is now lower than readState.Index' (duration: 133.893418ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:14:47.184186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.409979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:47.184232Z","caller":"traceutil/trace.go:171","msg":"trace[369457580] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:7937; }","duration":"234.496352ms","start":"2026-03-14T07:14:46.949721Z","end":"2026-03-14T07:14:47.184218Z","steps":["trace[369457580] 'agreement among raft nodes before linearized reading' (duration: 234.399529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:47.184232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.769354ms","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-03-14T07:14:47.184269Z","caller":"traceutil/trace.go:171","msg":"trace[1580671991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7937; }","duration":"111.861787ms","start":"2026-03-14T07:14:47.072397Z","end":"2026-03-14T07:14:47.184259Z","steps":["trace[1580671991] 'agreement among raft nodes before linearized reading' (duration: 111.743744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:47.18456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.279951ms","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-03-14T07:14:47.184832Z","caller":"traceutil/trace.go:171","msg":"trace[1104715547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7937; }","duration":"108.59539ms","start":"2026-03-14T07:14:47.076209Z","end":"2026-03-14T07:14:47.184805Z","steps":["trace[1104715547] 'agreement among raft nodes before linearized reading' (duration: 107.986753ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:18:23.996353Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e10bb51ed7578852","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-14T07:18:24.004569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-14T07:18:24.004674Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-14T07:19:26.244739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7741} {"level":"info","ts":"2026-03-14T07:19:26.305489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7741,"took":"59.019471ms","hash":2067811715,"current-db-size-bytes":42897408,"current-db-size":"43 MB","current-db-size-in-use-bytes":25903104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-14T07:19:26.305546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2067811715,"revision":7741,"compact-revision":5768} {"level":"info","ts":"2026-03-14T07:24:26.251695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9991} {"level":"info","ts":"2026-03-14T07:24:26.32862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9991,"took":"74.949616ms","hash":145552640,"current-db-size-bytes":42897408,"current-db-size":"43 MB","current-db-size-in-use-bytes":17317888,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-14T07:24:26.328677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":145552640,"revision":9991,"compact-revision":7741}