{"level":"warn","ts":"2026-09-10T01:46:51.102116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:46:51.102275Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.107:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.107:2380","--initial-cluster=instance=https://162.253.55.107:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.107:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.107:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-10T01:46:51.1024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:46:51.102441Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.107:2380"]} {"level":"info","ts":"2026-09-10T01:46:51.102478Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-10T01:46:51.103628Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"]} {"level":"info","ts":"2026-09-10T01:46:51.10378Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.107:2380"],"listen-peer-urls":["https://162.253.55.107:2380"],"advertise-client-urls":["https://162.253.55.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.107:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-10T01:46:51.108733Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.697023ms"} {"level":"info","ts":"2026-09-10T01:46:51.118697Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9139094afdb348df","cluster-id":"a56c8121e8858232"} {"level":"info","ts":"2026-09-10T01:46:51.118839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:46:51.118871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became follower at term 0"} {"level":"info","ts":"2026-09-10T01:46:51.118916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9139094afdb348df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:46:51.118936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became follower at term 1"} {"level":"info","ts":"2026-09-10T01:46:51.119024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=(10464405426860673247)"} {"level":"warn","ts":"2026-09-10T01:46:51.128551Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:46:51.130159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:46:51.131971Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-10T01:46:51.134261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9139094afdb348df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:46:51.134698Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9139094afdb348df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:46:51.134782Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T01:46:51.134819Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T01:46:51.134825Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-10T01:46:51.135672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df switched to configuration voters=(10464405426860673247)"} {"level":"info","ts":"2026-09-10T01:46:51.135895Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a56c8121e8858232","local-member-id":"9139094afdb348df","added-peer-id":"9139094afdb348df","added-peer-peer-urls":["https://162.253.55.107:2380"]} {"level":"info","ts":"2026-09-10T01:46:51.143611Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-10T01:46:51.143865Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9139094afdb348df","initial-advertise-peer-urls":["https://162.253.55.107:2380"],"listen-peer-urls":["https://162.253.55.107:2380"],"advertise-client-urls":["https://162.253.55.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:46:51.143901Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:46:51.144048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.107:2380"} {"level":"info","ts":"2026-09-10T01:46:51.144063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.107:2380"} {"level":"info","ts":"2026-09-10T01:46:51.72271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:46:51.722772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:46:51.722816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df received MsgPreVoteResp from 9139094afdb348df at term 1"} {"level":"info","ts":"2026-09-10T01:46:51.722837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:46:51.722853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df received MsgVoteResp from 9139094afdb348df at term 2"} {"level":"info","ts":"2026-09-10T01:46:51.722869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9139094afdb348df became leader at term 2"} {"level":"info","ts":"2026-09-10T01:46:51.722883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9139094afdb348df elected leader 9139094afdb348df at term 2"} {"level":"info","ts":"2026-09-10T01:46:51.72406Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9139094afdb348df","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.107:2379]}","request-path":"/0/members/9139094afdb348df/attributes","cluster-id":"a56c8121e8858232","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:46:51.72439Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:46:51.725271Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:46:51.725377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:46:51.72576Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:46:51.725785Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:46:51.725873Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a56c8121e8858232","local-member-id":"9139094afdb348df","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:46:51.726205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:46:51.726284Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:46:51.728475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:46:51.728776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.107:2379"} {"level":"warn","ts":"2026-09-10T01:46:53.761463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.400758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-09-10T01:46:53.761762Z","caller":"traceutil/trace.go:171","msg":"trace[1082846690] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"378.772408ms","start":"2026-09-10T01:46:53.382954Z","end":"2026-09-10T01:46:53.761727Z","steps":["trace[1082846690] 'process raft request' (duration: 164.352964ms)","trace[1082846690] 'compare' (duration: 213.150145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:46:53.761941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:46:53.38294Z","time spent":"378.909549ms","remote":"127.0.0.1:35504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:46:53.762196Z","caller":"traceutil/trace.go:171","msg":"trace[227768477] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:45; }","duration":"166.098898ms","start":"2026-09-10T01:46:53.596076Z","end":"2026-09-10T01:46:53.762175Z","steps":["trace[227768477] 'read index received' (duration: 34.991µs)","trace[227768477] 'applied index is now lower than readState.Index' (duration: 166.062667ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:46:53.762372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.287049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-10T01:46:53.76241Z","caller":"traceutil/trace.go:171","msg":"trace[1680416254] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:44; }","duration":"166.390299ms","start":"2026-09-10T01:46:53.596009Z","end":"2026-09-10T01:46:53.7624Z","steps":["trace[1680416254] 'agreement among raft nodes before linearized reading' (duration: 166.233978ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:46:53.762405Z","caller":"traceutil/trace.go:171","msg":"trace[1017787112] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"334.668102ms","start":"2026-09-10T01:46:53.427689Z","end":"2026-09-10T01:46:53.762357Z","steps":["trace[1017787112] 'process raft request' (duration: 334.40113ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:46:53.762485Z","caller":"traceutil/trace.go:171","msg":"trace[1208895397] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"373.691894ms","start":"2026-09-10T01:46:53.388777Z","end":"2026-09-10T01:46:53.762469Z","steps":["trace[1208895397] 'process raft request' (duration: 372.859318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:46:53.762638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:46:53.388766Z","time spent":"373.801314ms","remote":"127.0.0.1:35214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:46:53.762664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:46:53.427668Z","time spent":"334.825173ms","remote":"127.0.0.1:35504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:19.737937Z","caller":"traceutil/trace.go:171","msg":"trace[1768745689] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"101.380422ms","start":"2026-09-10T01:47:19.636524Z","end":"2026-09-10T01:47:19.737905Z","steps":["trace[1768745689] 'process raft request' (duration: 101.263188ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:31.414807Z","caller":"traceutil/trace.go:171","msg":"trace[1613025024] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"440.672409ms","start":"2026-09-10T01:47:30.974117Z","end":"2026-09-10T01:47:31.414789Z","steps":["trace[1613025024] 'process raft request' (duration: 440.574866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.414977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.974097Z","time spent":"440.831123ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.468498Z","caller":"traceutil/trace.go:171","msg":"trace[1973256210] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"493.700415ms","start":"2026-09-10T01:47:30.974754Z","end":"2026-09-10T01:47:31.468454Z","steps":["trace[1973256210] 'process raft request' (duration: 493.49996ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:31.468668Z","caller":"traceutil/trace.go:171","msg":"trace[1332546671] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"482.261447ms","start":"2026-09-10T01:47:30.986371Z","end":"2026-09-10T01:47:31.468632Z","steps":["trace[1332546671] 'process raft request' (duration: 482.027591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.468714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.974735Z","time spent":"493.86126ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.468783Z","caller":"traceutil/trace.go:171","msg":"trace[311180106] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"492.851141ms","start":"2026-09-10T01:47:30.975874Z","end":"2026-09-10T01:47:31.468725Z","steps":["trace[311180106] 'process raft request' (duration: 492.48437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.468836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.986336Z","time spent":"482.420981ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.46889Z","caller":"traceutil/trace.go:171","msg":"trace[1689645982] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"474.318729ms","start":"2026-09-10T01:47:30.994552Z","end":"2026-09-10T01:47:31.468871Z","steps":["trace[1689645982] 'process raft request' (duration: 474.01672ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:31.468843Z","caller":"traceutil/trace.go:171","msg":"trace[1872349732] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"479.328572ms","start":"2026-09-10T01:47:30.989452Z","end":"2026-09-10T01:47:31.468781Z","steps":["trace[1872349732] 'process raft request' (duration: 479.025844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.468984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.994539Z","time spent":"474.407762ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.469049Z","caller":"traceutil/trace.go:171","msg":"trace[1734844708] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"471.314092ms","start":"2026-09-10T01:47:30.997723Z","end":"2026-09-10T01:47:31.469037Z","steps":["trace[1734844708] 'process raft request' (duration: 470.924861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.469075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.989449Z","time spent":"479.540938ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:47:31.469023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.975867Z","time spent":"492.999634ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:47:31.469127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.997674Z","time spent":"471.425886ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.469127Z","caller":"traceutil/trace.go:171","msg":"trace[595523963] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"479.695992ms","start":"2026-09-10T01:47:30.989412Z","end":"2026-09-10T01:47:31.469108Z","steps":["trace[595523963] 'process raft request' (duration: 479.024504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.469204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.989403Z","time spent":"479.762525ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.469216Z","caller":"traceutil/trace.go:171","msg":"trace[1596456360] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"470.901351ms","start":"2026-09-10T01:47:30.998303Z","end":"2026-09-10T01:47:31.469204Z","steps":["trace[1596456360] 'process raft request' (duration: 470.423408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.469267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:30.998295Z","time spent":"470.953302ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.46938Z","caller":"traceutil/trace.go:171","msg":"trace[722641706] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"452.246419ms","start":"2026-09-10T01:47:31.017115Z","end":"2026-09-10T01:47:31.469362Z","steps":["trace[722641706] 'process raft request' (duration: 451.698484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:31.469504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:31.017097Z","time spent":"452.356832ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T01:47:31.469904Z","caller":"traceutil/trace.go:171","msg":"trace[160600818] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"426.868889ms","start":"2026-09-10T01:47:31.043013Z","end":"2026-09-10T01:47:31.469882Z","steps":["trace[160600818] 'process raft request' (duration: 426.493607ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:31.469923Z","caller":"traceutil/trace.go:171","msg":"trace[1318759581] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"299.933674ms","start":"2026-09-10T01:47:31.169973Z","end":"2026-09-10T01:47:31.469907Z","steps":["trace[1318759581] 'process raft request' (duration: 299.819681ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:31.469931Z","caller":"traceutil/trace.go:171","msg":"trace[1790463319] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"299.327118ms","start":"2026-09-10T01:47:31.170582Z","end":"2026-09-10T01:47:31.469909Z","steps":["trace[1790463319] 'read index received' (duration: 244.202733ms)","trace[1790463319] 'applied index is now lower than readState.Index' (duration: 55.123355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:47:31.469998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:31.169952Z","time spent":"300.008327ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:47:31.469998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:31.042979Z","time spent":"426.976962ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:47:31.470133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.535064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:47:31.470235Z","caller":"traceutil/trace.go:171","msg":"trace[1743457498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"299.659237ms","start":"2026-09-10T01:47:31.17056Z","end":"2026-09-10T01:47:31.470219Z","steps":["trace[1743457498] 'agreement among raft nodes before linearized reading' (duration: 299.4107ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:37.651132Z","caller":"traceutil/trace.go:171","msg":"trace[1406672875] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"130.773278ms","start":"2026-09-10T01:47:37.52033Z","end":"2026-09-10T01:47:37.651103Z","steps":["trace[1406672875] 'process raft request' (duration: 130.228983ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:38.033241Z","caller":"traceutil/trace.go:171","msg":"trace[2106508484] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"202.745856ms","start":"2026-09-10T01:47:37.830469Z","end":"2026-09-10T01:47:38.033215Z","steps":["trace[2106508484] 'read index received' (duration: 202.52923ms)","trace[2106508484] 'applied index is now lower than readState.Index' (duration: 215.516µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:47:38.033577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.092295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-10T01:47:38.03364Z","caller":"traceutil/trace.go:171","msg":"trace[158263765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"203.210909ms","start":"2026-09-10T01:47:37.830416Z","end":"2026-09-10T01:47:38.033627Z","steps":["trace[158263765] 'agreement among raft nodes before linearized reading' (duration: 202.979982ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:38.034113Z","caller":"traceutil/trace.go:171","msg":"trace[1906080835] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"301.540619ms","start":"2026-09-10T01:47:37.731835Z","end":"2026-09-10T01:47:38.033376Z","steps":["trace[1906080835] 'process raft request' (duration: 301.18203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:38.034302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:37.731792Z","time spent":"302.423032ms","remote":"127.0.0.1:35586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:47:38.627631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5251092200792224843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-10T01:47:38.681442Z","caller":"traceutil/trace.go:171","msg":"trace[1146704946] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"555.743821ms","start":"2026-09-10T01:47:38.125668Z","end":"2026-09-10T01:47:38.681411Z","steps":["trace[1146704946] 'process raft request' (duration: 555.541596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:38.681587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:38.125641Z","time spent":"555.858545ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:47:38.697777Z","caller":"traceutil/trace.go:171","msg":"trace[1674074654] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"559.753218ms","start":"2026-09-10T01:47:38.137997Z","end":"2026-09-10T01:47:38.697751Z","steps":["trace[1674074654] 'process raft request' (duration: 559.418719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:38.69791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:38.13798Z","time spent":"559.852851ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:47:38.699019Z","caller":"traceutil/trace.go:171","msg":"trace[440711961] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:661; }","duration":"571.459288ms","start":"2026-09-10T01:47:38.127529Z","end":"2026-09-10T01:47:38.698989Z","steps":["trace[440711961] 'read index received' (duration: 553.551473ms)","trace[440711961] 'applied index is now lower than readState.Index' (duration: 17.905965ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:47:38.699132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.596542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:47:38.699182Z","caller":"traceutil/trace.go:171","msg":"trace[1930342822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"571.678283ms","start":"2026-09-10T01:47:38.127491Z","end":"2026-09-10T01:47:38.699169Z","steps":["trace[1930342822] 'agreement among raft nodes before linearized reading' (duration: 571.598401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:38.699217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:38.127474Z","time spent":"571.736065ms","remote":"127.0.0.1:35064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T01:47:38.69946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.256411ms","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-09-10T01:47:38.699544Z","caller":"traceutil/trace.go:171","msg":"trace[1486505521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"332.383024ms","start":"2026-09-10T01:47:38.367137Z","end":"2026-09-10T01:47:38.69952Z","steps":["trace[1486505521] 'agreement among raft nodes before linearized reading' (duration: 332.198289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:38.699593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:38.367122Z","time spent":"332.461517ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-10T01:47:38.699062Z","caller":"traceutil/trace.go:171","msg":"trace[1655411132] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"191.474673ms","start":"2026-09-10T01:47:38.507556Z","end":"2026-09-10T01:47:38.69903Z","steps":["trace[1655411132] 'process raft request' (duration: 190.207799ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.302779Z","caller":"traceutil/trace.go:171","msg":"trace[9139914] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"116.760784ms","start":"2026-09-10T01:47:40.185995Z","end":"2026-09-10T01:47:40.302756Z","steps":["trace[9139914] 'process raft request' (duration: 116.622451ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.302963Z","caller":"traceutil/trace.go:171","msg":"trace[1329317125] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"113.283774ms","start":"2026-09-10T01:47:40.189665Z","end":"2026-09-10T01:47:40.302949Z","steps":["trace[1329317125] 'process raft request' (duration: 113.038838ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.340886Z","caller":"traceutil/trace.go:171","msg":"trace[461066839] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:690; }","duration":"117.616417ms","start":"2026-09-10T01:47:40.223224Z","end":"2026-09-10T01:47:40.34084Z","steps":["trace[461066839] 'read index received' (duration: 79.462765ms)","trace[461066839] 'applied index is now lower than readState.Index' (duration: 38.152142ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:47:40.340993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.749471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:47:40.341072Z","caller":"traceutil/trace.go:171","msg":"trace[613462235] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"111.94949ms","start":"2026-09-10T01:47:40.229085Z","end":"2026-09-10T01:47:40.341035Z","steps":["trace[613462235] 'process raft request' (duration: 111.789885ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.341085Z","caller":"traceutil/trace.go:171","msg":"trace[321883913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"117.878044ms","start":"2026-09-10T01:47:40.223189Z","end":"2026-09-10T01:47:40.341068Z","steps":["trace[321883913] 'agreement among raft nodes before linearized reading' (duration: 117.748041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:40.347823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.359364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:47:40.347882Z","caller":"traceutil/trace.go:171","msg":"trace[26731741] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:674; }","duration":"106.461236ms","start":"2026-09-10T01:47:40.241406Z","end":"2026-09-10T01:47:40.347867Z","steps":["trace[26731741] 'agreement among raft nodes before linearized reading' (duration: 99.608099ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.675866Z","caller":"traceutil/trace.go:171","msg":"trace[689156334] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"208.919389ms","start":"2026-09-10T01:47:40.466923Z","end":"2026-09-10T01:47:40.675842Z","steps":["trace[689156334] 'process raft request' (duration: 208.777096ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:47:40.7252Z","caller":"traceutil/trace.go:171","msg":"trace[826573097] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"256.900516ms","start":"2026-09-10T01:47:40.468274Z","end":"2026-09-10T01:47:40.725174Z","steps":["trace[826573097] 'process raft request' (duration: 256.65422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:55.469228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.488519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:47:55.469369Z","caller":"traceutil/trace.go:171","msg":"trace[1979957597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"341.702964ms","start":"2026-09-10T01:47:55.127638Z","end":"2026-09-10T01:47:55.469341Z","steps":["trace[1979957597] 'range keys from in-memory index tree' (duration: 341.325165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:47:55.469366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.723086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4kd6c\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-09-10T01:47:55.469424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:47:55.127615Z","time spent":"341.798917ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T01:47:55.469467Z","caller":"traceutil/trace.go:171","msg":"trace[984089868] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4kd6c; range_end:; response_count:1; response_revision:806; }","duration":"259.881311ms","start":"2026-09-10T01:47:55.209563Z","end":"2026-09-10T01:47:55.469444Z","steps":["trace[984089868] 'range keys from in-memory index tree' (duration: 259.538072ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:48:28.492803Z","caller":"traceutil/trace.go:171","msg":"trace[1745382864] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"144.615797ms","start":"2026-09-10T01:48:28.348166Z","end":"2026-09-10T01:48:28.492782Z","steps":["trace[1745382864] 'process raft request' (duration: 144.346162ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:48:44.700487Z","caller":"traceutil/trace.go:171","msg":"trace[1569350792] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"110.712287ms","start":"2026-09-10T01:48:44.589751Z","end":"2026-09-10T01:48:44.700463Z","steps":["trace[1569350792] 'read index received' (duration: 110.402592ms)","trace[1569350792] 'applied index is now lower than readState.Index' (duration: 307.535µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:48:44.700506Z","caller":"traceutil/trace.go:171","msg":"trace[1861785158] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"122.159839ms","start":"2026-09-10T01:48:44.578319Z","end":"2026-09-10T01:48:44.700479Z","steps":["trace[1861785158] 'process raft request' (duration: 121.929566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:48:44.700679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.9202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-10T01:48:44.700733Z","caller":"traceutil/trace.go:171","msg":"trace[472806836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1360; }","duration":"111.005511ms","start":"2026-09-10T01:48:44.589711Z","end":"2026-09-10T01:48:44.700716Z","steps":["trace[472806836] 'agreement among raft nodes before linearized reading' (duration: 110.861949ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:48:51.775586Z","caller":"traceutil/trace.go:171","msg":"trace[434058649] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"186.520155ms","start":"2026-09-10T01:48:51.589035Z","end":"2026-09-10T01:48:51.775555Z","steps":["trace[434058649] 'process raft request' (duration: 186.377622ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:48:51.821443Z","caller":"traceutil/trace.go:171","msg":"trace[1723222591] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"135.976798ms","start":"2026-09-10T01:48:51.685444Z","end":"2026-09-10T01:48:51.821421Z","steps":["trace[1723222591] 'process raft request' (duration: 135.859127ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:07.050859Z","caller":"traceutil/trace.go:171","msg":"trace[648825066] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"110.072808ms","start":"2026-09-10T01:49:06.940765Z","end":"2026-09-10T01:49:07.050837Z","steps":["trace[648825066] 'process raft request' (duration: 109.920326ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:07.050924Z","caller":"traceutil/trace.go:171","msg":"trace[1173779381] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"112.813647ms","start":"2026-09-10T01:49:06.938034Z","end":"2026-09-10T01:49:07.050848Z","steps":["trace[1173779381] 'process raft request' (duration: 90.322327ms)","trace[1173779381] 'compare' (duration: 22.211046ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:49:07.244396Z","caller":"traceutil/trace.go:171","msg":"trace[745609984] linearizableReadLoop","detail":"{readStateIndex:1819; appliedIndex:1817; }","duration":"117.827535ms","start":"2026-09-10T01:49:07.126548Z","end":"2026-09-10T01:49:07.244375Z","steps":["trace[745609984] 'read index received' (duration: 104.298352ms)","trace[745609984] 'applied index is now lower than readState.Index' (duration: 13.528213ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:49:07.244491Z","caller":"traceutil/trace.go:171","msg":"trace[655697630] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"119.885764ms","start":"2026-09-10T01:49:07.124596Z","end":"2026-09-10T01:49:07.244481Z","steps":["trace[655697630] 'process raft request' (duration: 119.69392ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:07.244592Z","caller":"traceutil/trace.go:171","msg":"trace[191952656] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"121.123331ms","start":"2026-09-10T01:49:07.123441Z","end":"2026-09-10T01:49:07.244564Z","steps":["trace[191952656] 'process raft request' (duration: 107.352915ms)","trace[191952656] 'compare' (duration: 13.159497ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:49:07.244707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.18024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:49:07.244739Z","caller":"traceutil/trace.go:171","msg":"trace[940041443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1753; }","duration":"118.25558ms","start":"2026-09-10T01:49:07.126474Z","end":"2026-09-10T01:49:07.24473Z","steps":["trace[940041443] 'agreement among raft nodes before linearized reading' (duration: 118.167379ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:17.457746Z","caller":"traceutil/trace.go:171","msg":"trace[1977208371] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"448.112896ms","start":"2026-09-10T01:49:17.009606Z","end":"2026-09-10T01:49:17.457719Z","steps":["trace[1977208371] 'process raft request' (duration: 447.950143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.458112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.009566Z","time spent":"448.367749ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:49:17.463224Z","caller":"traceutil/trace.go:171","msg":"trace[1654424730] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"452.13026ms","start":"2026-09-10T01:49:17.011072Z","end":"2026-09-10T01:49:17.463202Z","steps":["trace[1654424730] 'process raft request' (duration: 451.992278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.463368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.011045Z","time spent":"452.247172ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:49:17.463686Z","caller":"traceutil/trace.go:171","msg":"trace[1166613638] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"450.721151ms","start":"2026-09-10T01:49:17.012952Z","end":"2026-09-10T01:49:17.463673Z","steps":["trace[1166613638] 'process raft request' (duration: 450.216425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.463748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.012927Z","time spent":"450.790572ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:49:17.463693Z","caller":"traceutil/trace.go:171","msg":"trace[129781327] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1964; }","duration":"394.84876ms","start":"2026-09-10T01:49:17.068807Z","end":"2026-09-10T01:49:17.463656Z","steps":["trace[129781327] 'read index received' (duration: 388.722497ms)","trace[129781327] 'applied index is now lower than readState.Index' (duration: 6.122073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:49:17.464122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.318107ms","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":"warn","ts":"2026-09-10T01:49:17.464246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.541013ms","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-09-10T01:49:17.464301Z","caller":"traceutil/trace.go:171","msg":"trace[741766261] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1900; }","duration":"371.630534ms","start":"2026-09-10T01:49:17.092654Z","end":"2026-09-10T01:49:17.464285Z","steps":["trace[741766261] 'agreement among raft nodes before linearized reading' (duration: 371.479862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.464333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.092636Z","time spent":"371.688505ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-10T01:49:17.46424Z","caller":"traceutil/trace.go:171","msg":"trace[2014336716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"395.445518ms","start":"2026-09-10T01:49:17.068751Z","end":"2026-09-10T01:49:17.464197Z","steps":["trace[2014336716] 'agreement among raft nodes before linearized reading' (duration: 395.211535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.464433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.673458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:49:17.464465Z","caller":"traceutil/trace.go:171","msg":"trace[1773928484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"336.732538ms","start":"2026-09-10T01:49:17.127717Z","end":"2026-09-10T01:49:17.46445Z","steps":["trace[1773928484] 'agreement among raft nodes before linearized reading' (duration: 336.678468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.464488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.127706Z","time spent":"336.777019ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T01:49:17.464465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.879781ms","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-09-10T01:49:17.464543Z","caller":"traceutil/trace.go:171","msg":"trace[1037599462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1900; }","duration":"133.027202ms","start":"2026-09-10T01:49:17.331494Z","end":"2026-09-10T01:49:17.464521Z","steps":["trace[1037599462] 'agreement among raft nodes before linearized reading' (duration: 132.769229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.464665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:49:17.068734Z","time spent":"395.762193ms","remote":"127.0.0.1:51330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-10T01:49:17.625976Z","caller":"traceutil/trace.go:171","msg":"trace[1945749074] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"156.39168ms","start":"2026-09-10T01:49:17.469564Z","end":"2026-09-10T01:49:17.625956Z","steps":["trace[1945749074] 'process raft request' (duration: 136.350317ms)","trace[1945749074] 'compare' (duration: 19.899061ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:49:17.626117Z","caller":"traceutil/trace.go:171","msg":"trace[441519869] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"155.811513ms","start":"2026-09-10T01:49:17.470296Z","end":"2026-09-10T01:49:17.626108Z","steps":["trace[441519869] 'process raft request' (duration: 155.62149ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:17.62612Z","caller":"traceutil/trace.go:171","msg":"trace[1479745022] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1969; }","duration":"132.734757ms","start":"2026-09-10T01:49:17.493355Z","end":"2026-09-10T01:49:17.62609Z","steps":["trace[1479745022] 'read index received' (duration: 112.502372ms)","trace[1479745022] 'applied index is now lower than readState.Index' (duration: 20.230295ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:49:17.626246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.880999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:49:17.626283Z","caller":"traceutil/trace.go:171","msg":"trace[404988847] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1903; }","duration":"132.952271ms","start":"2026-09-10T01:49:17.493323Z","end":"2026-09-10T01:49:17.626275Z","steps":["trace[404988847] 'agreement among raft nodes before linearized reading' (duration: 132.8838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:49:17.626403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.444947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-10T01:49:17.626451Z","caller":"traceutil/trace.go:171","msg":"trace[1981613600] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1903; }","duration":"127.587989ms","start":"2026-09-10T01:49:17.498848Z","end":"2026-09-10T01:49:17.626436Z","steps":["trace[1981613600] 'agreement among raft nodes before linearized reading' (duration: 127.385475ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:29.662702Z","caller":"traceutil/trace.go:171","msg":"trace[1939626081] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"109.820101ms","start":"2026-09-10T01:49:29.552822Z","end":"2026-09-10T01:49:29.662642Z","steps":["trace[1939626081] 'process raft request' (duration: 40.68812ms)","trace[1939626081] 'compare' (duration: 68.864938ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:49:29.881014Z","caller":"traceutil/trace.go:171","msg":"trace[578374112] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"132.165042ms","start":"2026-09-10T01:49:29.748821Z","end":"2026-09-10T01:49:29.880986Z","steps":["trace[578374112] 'process raft request' (duration: 131.95023ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:49:29.89481Z","caller":"traceutil/trace.go:171","msg":"trace[1081919205] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"145.089721ms","start":"2026-09-10T01:49:29.749698Z","end":"2026-09-10T01:49:29.894787Z","steps":["trace[1081919205] 'process raft request' (duration: 144.9738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.44796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.290514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:50:06.448046Z","caller":"traceutil/trace.go:171","msg":"trace[2126765294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2214; }","duration":"320.411385ms","start":"2026-09-10T01:50:06.127614Z","end":"2026-09-10T01:50:06.448025Z","steps":["trace[2126765294] 'range keys from in-memory index tree' (duration: 320.210543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.448112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.127573Z","time spent":"320.529036ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T01:50:06.448134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.337089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-10T01:50:06.448232Z","caller":"traceutil/trace.go:171","msg":"trace[282864240] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2214; }","duration":"351.485081ms","start":"2026-09-10T01:50:06.096727Z","end":"2026-09-10T01:50:06.448212Z","steps":["trace[282864240] 'range keys from in-memory index tree' (duration: 351.100817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.448281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.096709Z","time spent":"351.556892ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T01:50:06.44856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.077474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"warn","ts":"2026-09-10T01:50:06.44862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.098571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:50:06.44895Z","caller":"traceutil/trace.go:171","msg":"trace[1678467887] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"298.349395ms","start":"2026-09-10T01:50:06.150579Z","end":"2026-09-10T01:50:06.448929Z","steps":["trace[1678467887] 'process raft request' (duration: 184.825238ms)","trace[1678467887] 'compare' (duration: 112.683257ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:06.4502Z","caller":"traceutil/trace.go:171","msg":"trace[402945135] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2214; }","duration":"339.298638ms","start":"2026-09-10T01:50:06.109367Z","end":"2026-09-10T01:50:06.448666Z","steps":["trace[402945135] 'range keys from in-memory index tree' (duration: 338.68765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.450391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.109335Z","time spent":"340.932528ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12407,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-10T01:50:06.850233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.746536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:50:06.850446Z","caller":"traceutil/trace.go:171","msg":"trace[1735715602] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"397.22315ms","start":"2026-09-10T01:50:06.453191Z","end":"2026-09-10T01:50:06.850415Z","steps":["trace[1735715602] 'process raft request' (duration: 173.1194ms)","trace[1735715602] 'compare' (duration: 223.403222ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:06.850906Z","caller":"traceutil/trace.go:171","msg":"trace[1545668064] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2295; }","duration":"390.678833ms","start":"2026-09-10T01:50:06.460202Z","end":"2026-09-10T01:50:06.850881Z","steps":["trace[1545668064] 'read index received' (duration: 166.131767ms)","trace[1545668064] 'applied index is now lower than readState.Index' (duration: 224.544376ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:50:06.850966Z","caller":"traceutil/trace.go:171","msg":"trace[139761666] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"364.440754ms","start":"2026-09-10T01:50:06.486501Z","end":"2026-09-10T01:50:06.850942Z","steps":["trace[139761666] 'process raft request' (duration: 363.926987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.851104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.892745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T01:50:06.851137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.486438Z","time spent":"364.612895ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:50:06.851041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.453162Z","time spent":"397.559434ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:50:06.851175Z","caller":"traceutil/trace.go:171","msg":"trace[1388505394] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2217; }","duration":"391.004606ms","start":"2026-09-10T01:50:06.460154Z","end":"2026-09-10T01:50:06.851158Z","steps":["trace[1388505394] 'agreement among raft nodes before linearized reading' (duration: 390.873985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:50:06.851178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.174066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T01:50:06.851214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:50:06.46014Z","time spent":"391.066508ms","remote":"127.0.0.1:35528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-10T01:50:06.851256Z","caller":"traceutil/trace.go:171","msg":"trace[696223782] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2217; }","duration":"227.307568ms","start":"2026-09-10T01:50:06.62393Z","end":"2026-09-10T01:50:06.851237Z","steps":["trace[696223782] 'agreement among raft nodes before linearized reading' (duration: 227.192137ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:50:46.835382Z","caller":"traceutil/trace.go:171","msg":"trace[229738095] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"247.884387ms","start":"2026-09-10T01:50:46.587473Z","end":"2026-09-10T01:50:46.835357Z","steps":["trace[229738095] 'process raft request' (duration: 247.760536ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:51:23.247824Z","caller":"traceutil/trace.go:171","msg":"trace[740302282] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2832; }","duration":"104.649376ms","start":"2026-09-10T01:51:23.143131Z","end":"2026-09-10T01:51:23.24778Z","steps":["trace[740302282] 'read index received' (duration: 7.126219ms)","trace[740302282] 'applied index is now lower than readState.Index' (duration: 97.522017ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:51:23.248015Z","caller":"traceutil/trace.go:171","msg":"trace[451812564] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"131.274622ms","start":"2026-09-10T01:51:23.116723Z","end":"2026-09-10T01:51:23.247998Z","steps":["trace[451812564] 'process raft request' (duration: 33.588818ms)","trace[451812564] 'compare' (duration: 97.311829ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:51:23.248348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.200698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-10T01:51:23.248396Z","caller":"traceutil/trace.go:171","msg":"trace[1456075838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2736; }","duration":"105.27613ms","start":"2026-09-10T01:51:23.143108Z","end":"2026-09-10T01:51:23.248384Z","steps":["trace[1456075838] 'agreement among raft nodes before linearized reading' (duration: 105.075093ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:03.843765Z","caller":"traceutil/trace.go:171","msg":"trace[2063168167] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"108.619078ms","start":"2026-09-10T01:56:03.735124Z","end":"2026-09-10T01:56:03.843744Z","steps":["trace[2063168167] 'process raft request' (duration: 77.009604ms)","trace[2063168167] 'compare' (duration: 31.378849ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:56:06.776296Z","caller":"traceutil/trace.go:171","msg":"trace[1683330464] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"141.550692ms","start":"2026-09-10T01:56:06.63472Z","end":"2026-09-10T01:56:06.776271Z","steps":["trace[1683330464] 'process raft request' (duration: 141.379449ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:06.826558Z","caller":"traceutil/trace.go:171","msg":"trace[472441903] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"181.632364ms","start":"2026-09-10T01:56:06.64491Z","end":"2026-09-10T01:56:06.826543Z","steps":["trace[472441903] 'process raft request' (duration: 181.512002ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:12.466118Z","caller":"traceutil/trace.go:171","msg":"trace[656281190] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"155.074026ms","start":"2026-09-10T01:56:12.311011Z","end":"2026-09-10T01:56:12.466085Z","steps":["trace[656281190] 'process raft request' (duration: 152.326451ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:12.656661Z","caller":"traceutil/trace.go:171","msg":"trace[837790015] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5142; }","duration":"167.271905ms","start":"2026-09-10T01:56:12.489335Z","end":"2026-09-10T01:56:12.656607Z","steps":["trace[837790015] 'read index received' (duration: 126.187434ms)","trace[837790015] 'applied index is now lower than readState.Index' (duration: 41.083661ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:56:12.656691Z","caller":"traceutil/trace.go:171","msg":"trace[1662914588] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"170.821443ms","start":"2026-09-10T01:56:12.485814Z","end":"2026-09-10T01:56:12.656635Z","steps":["trace[1662914588] 'process raft request' (duration: 129.867993ms)","trace[1662914588] 'compare' (duration: 40.658325ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:56:12.65691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.53767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:56:12.657022Z","caller":"traceutil/trace.go:171","msg":"trace[877725539] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4959; }","duration":"167.706852ms","start":"2026-09-10T01:56:12.48929Z","end":"2026-09-10T01:56:12.656996Z","steps":["trace[877725539] 'agreement among raft nodes before linearized reading' (duration: 167.393207ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:13.072126Z","caller":"traceutil/trace.go:171","msg":"trace[1940369348] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"236.402694ms","start":"2026-09-10T01:56:12.835675Z","end":"2026-09-10T01:56:13.072077Z","steps":["trace[1940369348] 'process raft request' (duration: 187.048807ms)","trace[1940369348] 'compare' (duration: 49.199505ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:56:13.099558Z","caller":"traceutil/trace.go:171","msg":"trace[65534820] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5151; }","duration":"137.921213ms","start":"2026-09-10T01:56:12.961599Z","end":"2026-09-10T01:56:13.09952Z","steps":["trace[65534820] 'read index received' (duration: 61.20894ms)","trace[65534820] 'applied index is now lower than readState.Index' (duration: 76.710273ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:56:13.099602Z","caller":"traceutil/trace.go:171","msg":"trace[1935570219] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"260.295085ms","start":"2026-09-10T01:56:12.839279Z","end":"2026-09-10T01:56:13.099574Z","steps":["trace[1935570219] 'process raft request' (duration: 260.01154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:56:13.099868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.241768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-10T01:56:13.099946Z","caller":"traceutil/trace.go:171","msg":"trace[1872970147] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4969; }","duration":"138.379401ms","start":"2026-09-10T01:56:12.96154Z","end":"2026-09-10T01:56:13.099919Z","steps":["trace[1872970147] 'agreement among raft nodes before linearized reading' (duration: 138.113726ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:13.304461Z","caller":"traceutil/trace.go:171","msg":"trace[1109532563] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"175.148271ms","start":"2026-09-10T01:56:13.129274Z","end":"2026-09-10T01:56:13.304422Z","steps":["trace[1109532563] 'read index received' (duration: 81.182076ms)","trace[1109532563] 'applied index is now lower than readState.Index' (duration: 93.964375ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:56:13.304494Z","caller":"traceutil/trace.go:171","msg":"trace[1789775392] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"183.350214ms","start":"2026-09-10T01:56:13.121099Z","end":"2026-09-10T01:56:13.304449Z","steps":["trace[1789775392] 'process raft request' (duration: 89.606403ms)","trace[1789775392] 'compare' (duration: 93.437925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:56:13.304809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.500807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:56:13.304924Z","caller":"traceutil/trace.go:171","msg":"trace[699892459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4975; }","duration":"175.68735ms","start":"2026-09-10T01:56:13.129217Z","end":"2026-09-10T01:56:13.304904Z","steps":["trace[699892459] 'agreement among raft nodes before linearized reading' (duration: 175.285334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:56:28.235105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.32165ms","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-09-10T01:56:28.235233Z","caller":"traceutil/trace.go:171","msg":"trace[846154742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5206; }","duration":"105.509982ms","start":"2026-09-10T01:56:28.1297Z","end":"2026-09-10T01:56:28.23521Z","steps":["trace[846154742] 'range keys from in-memory index tree' (duration: 104.559508ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:51.762275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-09-10T01:56:51.834309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"70.615516ms","hash":2715128650,"current-db-size-bytes":52752384,"current-db-size":"53 MB","current-db-size-in-use-bytes":28999680,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-10T01:56:51.834361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2715128650,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-09-10T01:57:02.549223Z","caller":"traceutil/trace.go:171","msg":"trace[1986917878] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"117.897737ms","start":"2026-09-10T01:57:02.43129Z","end":"2026-09-10T01:57:02.549188Z","steps":["trace[1986917878] 'process raft request' (duration: 117.493481ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:02.549457Z","caller":"traceutil/trace.go:171","msg":"trace[2075559403] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"116.068508ms","start":"2026-09-10T01:57:02.433365Z","end":"2026-09-10T01:57:02.549434Z","steps":["trace[2075559403] 'process raft request' (duration: 115.761204ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:51.768905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5437} {"level":"info","ts":"2026-09-10T02:01:51.85768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5437,"took":"86.464671ms","hash":3058037973,"current-db-size-bytes":52752384,"current-db-size":"53 MB","current-db-size-in-use-bytes":19021824,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T02:01:51.857748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3058037973,"revision":5437,"compact-revision":2885} {"level":"info","ts":"2026-09-10T02:02:06.662262Z","caller":"traceutil/trace.go:171","msg":"trace[486438099] transaction","detail":"{read_only:false; response_revision:7496; number_of_response:1; }","duration":"383.406584ms","start":"2026-09-10T02:02:06.278828Z","end":"2026-09-10T02:02:06.662235Z","steps":["trace[486438099] 'process raft request' (duration: 383.224629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:02:06.662468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:02:06.278802Z","time spent":"383.535788ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:02:06.664954Z","caller":"traceutil/trace.go:171","msg":"trace[2018551327] transaction","detail":"{read_only:false; response_revision:7497; number_of_response:1; }","duration":"386.097564ms","start":"2026-09-10T02:02:06.278842Z","end":"2026-09-10T02:02:06.664939Z","steps":["trace[2018551327] 'process raft request' (duration: 386.026302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:02:06.665072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:02:06.278812Z","time spent":"386.186777ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:02:36.544922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.105679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"warn","ts":"2026-09-10T02:02:36.545012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.043317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-09-10T02:02:36.545027Z","caller":"traceutil/trace.go:171","msg":"trace[671260165] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:7647; }","duration":"123.246112ms","start":"2026-09-10T02:02:36.421756Z","end":"2026-09-10T02:02:36.545002Z","steps":["trace[671260165] 'range keys from in-memory index tree' (duration: 123.040777ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:02:36.545081Z","caller":"traceutil/trace.go:171","msg":"trace[1734666224] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:7647; }","duration":"182.134489ms","start":"2026-09-10T02:02:36.362922Z","end":"2026-09-10T02:02:36.545057Z","steps":["trace[1734666224] 'range keys from in-memory index tree' (duration: 181.866362ms)"],"step_count":1}