{"level":"warn","ts":"2026-07-21T06:32:44.07673Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T06:32:44.076823Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-07-21T06:32:44.07689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T06:32:44.076897Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-21T06:32:44.076915Z","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-07-21T06:32:44.077725Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-21T06:32:44.077804Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-07-21T06:32:44.081686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.658406ms"} {"level":"info","ts":"2026-07-21T06:32:44.088968Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-21T06:32:44.089051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:32:44.089075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-21T06:32:44.089089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:32:44.089104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-21T06:32:44.089142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-21T06:32:44.098264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:32:44.101519Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:32:44.109948Z","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-07-21T06:32:44.118267Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:32:44.118687Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:32:44.118991Z","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-07-21T06:32:44.119074Z","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-07-21T06:32:44.119086Z","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-07-21T06:32:44.123196Z","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-07-21T06:32:44.123294Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-21T06:32:44.123322Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-21T06:32:44.123473Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:32:44.123519Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:32:44.152626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-21T06:32:44.153082Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-21T06:32:44.289467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:32:44.289507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:32:44.289529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-21T06:32:44.289539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:32:44.289545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-21T06:32:44.289631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-21T06:32:44.28964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-21T06:32:44.32181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:44.352769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:32:44.352938Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:32:44.353008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:32:44.355324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:32:44.357706Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:32:44.357778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:32:44.358514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-21T06:32:44.380981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:44.381132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:44.381164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:46.278909Z","caller":"traceutil/trace.go:171","msg":"trace[707555520] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:5; }","duration":"269.364716ms","start":"2026-07-21T06:32:46.00952Z","end":"2026-07-21T06:32:46.278885Z","steps":["trace[707555520] 'read index received' (duration: 269.1233ms)","trace[707555520] 'applied index is now lower than readState.Index' (duration: 240.576µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:32:46.27907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.868845ms","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-07-21T06:32:46.279079Z","caller":"traceutil/trace.go:171","msg":"trace[1673953795] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"274.825805ms","start":"2026-07-21T06:32:46.004197Z","end":"2026-07-21T06:32:46.279023Z","steps":["trace[1673953795] 'process raft request' (duration: 274.420991ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.2792Z","caller":"traceutil/trace.go:171","msg":"trace[1737002479] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"273.86366ms","start":"2026-07-21T06:32:46.005329Z","end":"2026-07-21T06:32:46.279193Z","steps":["trace[1737002479] 'process raft request' (duration: 273.523065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.950324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T06:32:46.279291Z","caller":"traceutil/trace.go:171","msg":"trace[1259710688] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:8; }","duration":"275.17264ms","start":"2026-07-21T06:32:46.004104Z","end":"2026-07-21T06:32:46.279276Z","steps":["trace[1259710688] 'agreement among raft nodes before linearized reading' (duration: 274.959032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.987044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T06:32:46.279336Z","caller":"traceutil/trace.go:171","msg":"trace[661153856] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"274.984053ms","start":"2026-07-21T06:32:46.00434Z","end":"2026-07-21T06:32:46.279324Z","steps":["trace[661153856] 'process raft request' (duration: 274.46408ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.279346Z","caller":"traceutil/trace.go:171","msg":"trace[1597860811] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:0; response_revision:8; }","duration":"277.037273ms","start":"2026-07-21T06:32:46.002303Z","end":"2026-07-21T06:32:46.27934Z","steps":["trace[1597860811] 'agreement among raft nodes before linearized reading' (duration: 277.004983ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.279158Z","caller":"traceutil/trace.go:171","msg":"trace[113847419] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:8; }","duration":"274.975433ms","start":"2026-07-21T06:32:46.004167Z","end":"2026-07-21T06:32:46.279143Z","steps":["trace[113847419] 'agreement among raft nodes before linearized reading' (duration: 274.769675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.550229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T06:32:46.279484Z","caller":"traceutil/trace.go:171","msg":"trace[1777217252] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:8; }","duration":"359.663747ms","start":"2026-07-21T06:32:45.919796Z","end":"2026-07-21T06:32:46.27946Z","steps":["trace[1777217252] 'agreement among raft nodes before linearized reading' (duration: 359.49697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:45.919782Z","time spent":"359.743546ms","remote":"127.0.0.1:43760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-21T06:32:46.278925Z","caller":"traceutil/trace.go:171","msg":"trace[2076397907] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"274.638558ms","start":"2026-07-21T06:32:46.004258Z","end":"2026-07-21T06:32:46.278896Z","steps":["trace[2076397907] 'process raft request' (duration: 274.478691ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.279626Z","caller":"traceutil/trace.go:171","msg":"trace[462152082] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"275.310188ms","start":"2026-07-21T06:32:46.004278Z","end":"2026-07-21T06:32:46.279588Z","steps":["trace[462152082] 'process raft request' (duration: 274.49855ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.27928Z","caller":"traceutil/trace.go:171","msg":"trace[1645912336] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"274.602179ms","start":"2026-07-21T06:32:46.004446Z","end":"2026-07-21T06:32:46.279048Z","steps":["trace[1645912336] 'process raft request' (duration: 274.380692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.019441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T06:32:46.279786Z","caller":"traceutil/trace.go:171","msg":"trace[1667191783] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:8; }","duration":"360.060901ms","start":"2026-07-21T06:32:45.919718Z","end":"2026-07-21T06:32:46.279778Z","steps":["trace[1667191783] 'agreement among raft nodes before linearized reading' (duration: 360.011912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.279807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:45.919709Z","time spent":"360.091641ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-21T06:32:46.59653Z","caller":"traceutil/trace.go:171","msg":"trace[1375886164] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:11; }","duration":"317.469658ms","start":"2026-07-21T06:32:46.279038Z","end":"2026-07-21T06:32:46.596508Z","steps":["trace[1375886164] 'read index received' (duration: 315.29468ms)","trace[1375886164] 'applied index is now lower than readState.Index' (duration: 2.174008ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:46.596707Z","caller":"traceutil/trace.go:171","msg":"trace[1468620003] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"579.326505ms","start":"2026-07-21T06:32:46.017351Z","end":"2026-07-21T06:32:46.596678Z","steps":["trace[1468620003] 'process raft request' (duration: 579.089819ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.596977Z","caller":"traceutil/trace.go:171","msg":"trace[218685361] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"586.606437ms","start":"2026-07-21T06:32:46.010337Z","end":"2026-07-21T06:32:46.596944Z","steps":["trace[218685361] 'process raft request' (duration: 584.022485ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597095Z","caller":"traceutil/trace.go:171","msg":"trace[174461971] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"313.157602ms","start":"2026-07-21T06:32:46.283927Z","end":"2026-07-21T06:32:46.597085Z","steps":["trace[174461971] 'process raft request' (duration: 312.825817ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597042Z","caller":"traceutil/trace.go:171","msg":"trace[1923352613] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"312.6003ms","start":"2026-07-21T06:32:46.284416Z","end":"2026-07-21T06:32:46.597016Z","steps":["trace[1923352613] 'process raft request' (duration: 312.515252ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597117Z","caller":"traceutil/trace.go:171","msg":"trace[974129384] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"312.974734ms","start":"2026-07-21T06:32:46.284121Z","end":"2026-07-21T06:32:46.597096Z","steps":["trace[974129384] 'process raft request' (duration: 312.735857ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597155Z","caller":"traceutil/trace.go:171","msg":"trace[1571677793] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"312.991094ms","start":"2026-07-21T06:32:46.284154Z","end":"2026-07-21T06:32:46.597145Z","steps":["trace[1571677793] 'process raft request' (duration: 312.724527ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597179Z","caller":"traceutil/trace.go:171","msg":"trace[1390007557] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"313.124932ms","start":"2026-07-21T06:32:46.284045Z","end":"2026-07-21T06:32:46.59717Z","steps":["trace[1390007557] 'process raft request' (duration: 312.763467ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597178Z","caller":"traceutil/trace.go:171","msg":"trace[1954114948] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"577.679549ms","start":"2026-07-21T06:32:46.019481Z","end":"2026-07-21T06:32:46.59716Z","steps":["trace[1954114948] 'process raft request' (duration: 576.996629ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597219Z","caller":"traceutil/trace.go:171","msg":"trace[1208529800] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"313.139912ms","start":"2026-07-21T06:32:46.284073Z","end":"2026-07-21T06:32:46.597212Z","steps":["trace[1208529800] 'process raft request' (duration: 312.757117ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.597266Z","caller":"traceutil/trace.go:171","msg":"trace[268722518] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"315.154862ms","start":"2026-07-21T06:32:46.282104Z","end":"2026-07-21T06:32:46.597258Z","steps":["trace[268722518] 'process raft request' (duration: 314.518192ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.59738Z","caller":"traceutil/trace.go:171","msg":"trace[1775021105] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"313.550586ms","start":"2026-07-21T06:32:46.283808Z","end":"2026-07-21T06:32:46.597358Z","steps":["trace[1775021105] 'process raft request' (duration: 312.921685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.597568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.181732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-21T06:32:46.597628Z","caller":"traceutil/trace.go:171","msg":"trace[401838079] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"315.285221ms","start":"2026-07-21T06:32:46.282329Z","end":"2026-07-21T06:32:46.597614Z","steps":["trace[401838079] 'agreement among raft nodes before linearized reading' (duration: 315.121052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.597668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.282312Z","time spent":"315.3458ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-21T06:32:46.597838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.690584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T06:32:46.597881Z","caller":"traceutil/trace.go:171","msg":"trace[836055473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"315.762764ms","start":"2026-07-21T06:32:46.282108Z","end":"2026-07-21T06:32:46.597871Z","steps":["trace[836055473] 'agreement among raft nodes before linearized reading' (duration: 315.32564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.59791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.282095Z","time spent":"315.808023ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:32:46.597909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.06352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-21T06:32:46.597962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.897301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-21T06:32:46.598012Z","caller":"traceutil/trace.go:171","msg":"trace[414051430] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"543.004379ms","start":"2026-07-21T06:32:46.054994Z","end":"2026-07-21T06:32:46.597998Z","steps":["trace[414051430] 'agreement among raft nodes before linearized reading' (duration: 542.453647ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:46.598015Z","caller":"traceutil/trace.go:171","msg":"trace[2018794459] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"526.240797ms","start":"2026-07-21T06:32:46.071751Z","end":"2026-07-21T06:32:46.597992Z","steps":["trace[2018794459] 'agreement among raft nodes before linearized reading' (duration: 525.611626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:46.598054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.054971Z","time spent":"543.074649ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-21T06:32:46.598072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.071727Z","time spent":"526.331884ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-21T06:32:46.599644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.017337Z","time spent":"579.411373ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.599775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.283921Z","time spent":"313.211391ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.59987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.284408Z","time spent":"312.730298ms","remote":"127.0.0.1:43814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.284115Z","time spent":"313.043203ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.284148Z","time spent":"313.034064ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.28404Z","time spent":"313.165291ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.019468Z","time spent":"577.741278ms","remote":"127.0.0.1:43614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.284059Z","time spent":"313.179381ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.282083Z","time spent":"315.24335ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.283795Z","time spent":"313.621195ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:32:46.600988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:32:46.010324Z","time spent":"586.777615ms","remote":"127.0.0.1:43412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:32:49.898752Z","caller":"traceutil/trace.go:171","msg":"trace[367793598] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"153.71905ms","start":"2026-07-21T06:32:49.745012Z","end":"2026-07-21T06:32:49.898731Z","steps":["trace[367793598] 'process raft request' (duration: 151.711911ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:49.900286Z","caller":"traceutil/trace.go:171","msg":"trace[986202216] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:299; }","duration":"149.698582ms","start":"2026-07-21T06:32:49.750564Z","end":"2026-07-21T06:32:49.900262Z","steps":["trace[986202216] 'read index received' (duration: 146.174499ms)","trace[986202216] 'applied index is now lower than readState.Index' (duration: 3.523173ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:32:49.900408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.82088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:32:49.900441Z","caller":"traceutil/trace.go:171","msg":"trace[1384327077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:292; }","duration":"149.87872ms","start":"2026-07-21T06:32:49.750551Z","end":"2026-07-21T06:32:49.90043Z","steps":["trace[1384327077] 'agreement among raft nodes before linearized reading' (duration: 149.794041ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:49.900439Z","caller":"traceutil/trace.go:171","msg":"trace[1805586746] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"153.58446ms","start":"2026-07-21T06:32:49.746806Z","end":"2026-07-21T06:32:49.90039Z","steps":["trace[1805586746] 'process raft request' (duration: 153.341093ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:50.023098Z","caller":"traceutil/trace.go:171","msg":"trace[1214759920] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"117.109342ms","start":"2026-07-21T06:32:49.90595Z","end":"2026-07-21T06:32:50.023059Z","steps":["trace[1214759920] 'process raft request' (duration: 96.518939ms)","trace[1214759920] 'compare' (duration: 20.408173ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:50.415932Z","caller":"traceutil/trace.go:171","msg":"trace[931193503] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"179.621493ms","start":"2026-07-21T06:32:50.236288Z","end":"2026-07-21T06:32:50.41591Z","steps":["trace[931193503] 'read index received' (duration: 126.252119ms)","trace[931193503] 'applied index is now lower than readState.Index' (duration: 53.368684ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:50.416014Z","caller":"traceutil/trace.go:171","msg":"trace[1880981990] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"201.049968ms","start":"2026-07-21T06:32:50.214958Z","end":"2026-07-21T06:32:50.416008Z","steps":["trace[1880981990] 'process raft request' (duration: 147.626985ms)","trace[1880981990] 'compare' (duration: 52.992536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:32:50.416082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.363528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:32:50.416161Z","caller":"traceutil/trace.go:171","msg":"trace[1058810421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:299; }","duration":"172.509627ms","start":"2026-07-21T06:32:50.243639Z","end":"2026-07-21T06:32:50.416148Z","steps":["trace[1058810421] 'agreement among raft nodes before linearized reading' (duration: 172.376049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:50.416156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.830641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-07-21T06:32:50.416262Z","caller":"traceutil/trace.go:171","msg":"trace[523432390] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:299; }","duration":"179.979819ms","start":"2026-07-21T06:32:50.236263Z","end":"2026-07-21T06:32:50.416243Z","steps":["trace[523432390] 'agreement among raft nodes before linearized reading' (duration: 179.772822ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:50.547974Z","caller":"traceutil/trace.go:171","msg":"trace[1078383614] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"111.849301ms","start":"2026-07-21T06:32:50.436084Z","end":"2026-07-21T06:32:50.547933Z","steps":["trace[1078383614] 'process raft request' (duration: 83.626799ms)","trace[1078383614] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/kube-apiserver-instance; req_size:7285; } (duration: 27.825937ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:50.806529Z","caller":"traceutil/trace.go:171","msg":"trace[372743539] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"209.714129ms","start":"2026-07-21T06:32:50.596793Z","end":"2026-07-21T06:32:50.806507Z","steps":["trace[372743539] 'process raft request' (duration: 120.640821ms)","trace[372743539] 'compare' (duration: 88.959579ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:50.806907Z","caller":"traceutil/trace.go:171","msg":"trace[1720106758] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"208.927226ms","start":"2026-07-21T06:32:50.597957Z","end":"2026-07-21T06:32:50.806885Z","steps":["trace[1720106758] 'process raft request' (duration: 208.815857ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:50.961861Z","caller":"traceutil/trace.go:171","msg":"trace[1817129871] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"144.163526ms","start":"2026-07-21T06:32:50.817674Z","end":"2026-07-21T06:32:50.961837Z","steps":["trace[1817129871] 'process raft request' (duration: 144.116605ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:50.961911Z","caller":"traceutil/trace.go:171","msg":"trace[153310716] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"145.718993ms","start":"2026-07-21T06:32:50.816157Z","end":"2026-07-21T06:32:50.961876Z","steps":["trace[153310716] 'process raft request' (duration: 124.829143ms)","trace[153310716] 'compare' (duration: 20.670411ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:51.168389Z","caller":"traceutil/trace.go:171","msg":"trace[60606687] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"122.093342ms","start":"2026-07-21T06:32:51.046272Z","end":"2026-07-21T06:32:51.168366Z","steps":["trace[60606687] 'process raft request' (duration: 122.050052ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:51.168537Z","caller":"traceutil/trace.go:171","msg":"trace[749675829] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"122.929275ms","start":"2026-07-21T06:32:51.04557Z","end":"2026-07-21T06:32:51.168499Z","steps":["trace[749675829] 'process raft request' (duration: 68.27119ms)","trace[749675829] 'compare' (duration: 54.286638ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:59.603921Z","caller":"traceutil/trace.go:171","msg":"trace[2031679664] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"123.06331ms","start":"2026-07-21T06:32:59.480832Z","end":"2026-07-21T06:32:59.603895Z","steps":["trace[2031679664] 'process raft request' (duration: 122.72091ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.516837Z","caller":"traceutil/trace.go:171","msg":"trace[852884728] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"174.774824ms","start":"2026-07-21T06:33:08.342043Z","end":"2026-07-21T06:33:08.516818Z","steps":["trace[852884728] 'process raft request' (duration: 125.777588ms)","trace[852884728] 'compare' (duration: 48.822394ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:08.516876Z","caller":"traceutil/trace.go:171","msg":"trace[25599517] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"173.661651ms","start":"2026-07-21T06:33:08.343181Z","end":"2026-07-21T06:33:08.516842Z","steps":["trace[25599517] 'process raft request' (duration: 173.55781ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.516906Z","caller":"traceutil/trace.go:171","msg":"trace[386784233] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"171.669927ms","start":"2026-07-21T06:33:08.345217Z","end":"2026-07-21T06:33:08.516886Z","steps":["trace[386784233] 'process raft request' (duration: 171.556056ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.516967Z","caller":"traceutil/trace.go:171","msg":"trace[591315059] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:455; }","duration":"153.667778ms","start":"2026-07-21T06:33:08.363294Z","end":"2026-07-21T06:33:08.516961Z","steps":["trace[591315059] 'read index received' (duration: 63.713174ms)","trace[591315059] 'applied index is now lower than readState.Index' (duration: 89.954064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:08.517054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.745199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:08.51705Z","caller":"traceutil/trace.go:171","msg":"trace[529223093] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"171.515745ms","start":"2026-07-21T06:33:08.345524Z","end":"2026-07-21T06:33:08.51704Z","steps":["trace[529223093] 'process raft request' (duration: 171.267722ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.517071Z","caller":"traceutil/trace.go:171","msg":"trace[438055098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"153.829881ms","start":"2026-07-21T06:33:08.363234Z","end":"2026-07-21T06:33:08.517064Z","steps":["trace[438055098] 'agreement among raft nodes before linearized reading' (duration: 153.745389ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.669613Z","caller":"traceutil/trace.go:171","msg":"trace[856302418] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"147.079858ms","start":"2026-07-21T06:33:08.522506Z","end":"2026-07-21T06:33:08.669586Z","steps":["trace[856302418] 'process raft request' (duration: 132.802205ms)","trace[856302418] 'compare' (duration: 13.594505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:08.670473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.489621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-07-21T06:33:08.671262Z","caller":"traceutil/trace.go:171","msg":"trace[1173534786] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:448; }","duration":"147.303092ms","start":"2026-07-21T06:33:08.52394Z","end":"2026-07-21T06:33:08.671243Z","steps":["trace[1173534786] 'agreement among raft nodes before linearized reading' (duration: 145.816564ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.679871Z","caller":"traceutil/trace.go:171","msg":"trace[2083124555] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:459; }","duration":"145.063724ms","start":"2026-07-21T06:33:08.523954Z","end":"2026-07-21T06:33:08.669017Z","steps":["trace[2083124555] 'read index received' (duration: 92.523925ms)","trace[2083124555] 'applied index is now lower than readState.Index' (duration: 52.538699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:08.680555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.39551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-21T06:33:08.680707Z","caller":"traceutil/trace.go:171","msg":"trace[1089639738] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:448; }","duration":"155.554631ms","start":"2026-07-21T06:33:08.525139Z","end":"2026-07-21T06:33:08.680693Z","steps":["trace[1089639738] 'agreement among raft nodes before linearized reading' (duration: 155.361369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:08.999424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.53324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:33:08.999508Z","caller":"traceutil/trace.go:171","msg":"trace[1083560933] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"240.22272ms","start":"2026-07-21T06:33:08.759274Z","end":"2026-07-21T06:33:08.999497Z","steps":["trace[1083560933] 'read index received' (duration: 38.388556ms)","trace[1083560933] 'applied index is now lower than readState.Index' (duration: 201.833384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:08.999568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.288901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:33:08.999591Z","caller":"traceutil/trace.go:171","msg":"trace[1757045150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"240.315751ms","start":"2026-07-21T06:33:08.759268Z","end":"2026-07-21T06:33:08.999584Z","steps":["trace[1757045150] 'agreement among raft nodes before linearized reading' (duration: 240.261ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:08.999723Z","caller":"traceutil/trace.go:171","msg":"trace[621194887] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"241.355625ms","start":"2026-07-21T06:33:08.758286Z","end":"2026-07-21T06:33:08.999641Z","steps":["trace[621194887] 'process raft request' (duration: 39.456291ms)","trace[621194887] 'compare' (duration: 201.424438ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:09.041833Z","caller":"traceutil/trace.go:171","msg":"trace[1525431191] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"278.03116ms","start":"2026-07-21T06:33:08.763772Z","end":"2026-07-21T06:33:09.041803Z","steps":["trace[1525431191] 'process raft request' (duration: 277.841538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:09.191999Z","caller":"traceutil/trace.go:171","msg":"trace[68676387] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"111.57071ms","start":"2026-07-21T06:33:09.080382Z","end":"2026-07-21T06:33:09.191953Z","steps":["trace[68676387] 'process raft request' (duration: 27.246279ms)","trace[68676387] 'compare' (duration: 83.970016ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:09.192078Z","caller":"traceutil/trace.go:171","msg":"trace[73799391] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"109.609724ms","start":"2026-07-21T06:33:09.082452Z","end":"2026-07-21T06:33:09.192062Z","steps":["trace[73799391] 'process raft request' (duration: 109.463772ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:09.19205Z","caller":"traceutil/trace.go:171","msg":"trace[848887823] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"108.023403ms","start":"2026-07-21T06:33:09.083996Z","end":"2026-07-21T06:33:09.19202Z","steps":["trace[848887823] 'process raft request' (duration: 107.962442ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:09.191999Z","caller":"traceutil/trace.go:171","msg":"trace[713882144] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"110.373714ms","start":"2026-07-21T06:33:09.081602Z","end":"2026-07-21T06:33:09.191975Z","steps":["trace[713882144] 'process raft request' (duration: 110.228971ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.122242Z","caller":"traceutil/trace.go:171","msg":"trace[1518821379] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"133.24452ms","start":"2026-07-21T06:33:20.988974Z","end":"2026-07-21T06:33:21.122218Z","steps":["trace[1518821379] 'process raft request' (duration: 133.172169ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.122285Z","caller":"traceutil/trace.go:171","msg":"trace[1237900592] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"139.899585ms","start":"2026-07-21T06:33:20.982354Z","end":"2026-07-21T06:33:21.122254Z","steps":["trace[1237900592] 'process raft request' (duration: 139.505485ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.12262Z","caller":"traceutil/trace.go:171","msg":"trace[1114298274] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"140.202142ms","start":"2026-07-21T06:33:20.982401Z","end":"2026-07-21T06:33:21.122604Z","steps":["trace[1114298274] 'process raft request' (duration: 139.579227ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.122938Z","caller":"traceutil/trace.go:171","msg":"trace[1588873398] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"134.004648ms","start":"2026-07-21T06:33:20.988917Z","end":"2026-07-21T06:33:21.122922Z","steps":["trace[1588873398] 'process raft request' (duration: 133.172578ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.123055Z","caller":"traceutil/trace.go:171","msg":"trace[172035973] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"140.444149ms","start":"2026-07-21T06:33:20.982594Z","end":"2026-07-21T06:33:21.123038Z","steps":["trace[172035973] 'process raft request' (duration: 139.445224ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.474757Z","caller":"traceutil/trace.go:171","msg":"trace[1855091194] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"482.418802ms","start":"2026-07-21T06:33:20.992312Z","end":"2026-07-21T06:33:21.474731Z","steps":["trace[1855091194] 'process raft request' (duration: 482.119775ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.474866Z","caller":"traceutil/trace.go:171","msg":"trace[106371751] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"466.098266ms","start":"2026-07-21T06:33:21.00874Z","end":"2026-07-21T06:33:21.474838Z","steps":["trace[106371751] 'process raft request' (duration: 465.882601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.474973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.008723Z","time spent":"466.198408ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:21.474982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:20.992303Z","time spent":"482.567035ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:21.474999Z","caller":"traceutil/trace.go:171","msg":"trace[1223846942] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"465.378799ms","start":"2026-07-21T06:33:21.009602Z","end":"2026-07-21T06:33:21.47498Z","steps":["trace[1223846942] 'process raft request' (duration: 465.166383ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.475348Z","caller":"traceutil/trace.go:171","msg":"trace[1450649220] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"465.528762ms","start":"2026-07-21T06:33:21.009811Z","end":"2026-07-21T06:33:21.47534Z","steps":["trace[1450649220] 'process raft request' (duration: 465.079751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.475406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.009803Z","time spent":"465.570663ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:33:21.47535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.009582Z","time spent":"465.710886ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:21.475741Z","caller":"traceutil/trace.go:171","msg":"trace[32003202] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"465.864131ms","start":"2026-07-21T06:33:21.009857Z","end":"2026-07-21T06:33:21.475721Z","steps":["trace[32003202] 'process raft request' (duration: 465.43348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.4758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.009848Z","time spent":"465.925122ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:21.476024Z","caller":"traceutil/trace.go:171","msg":"trace[135161117] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:558; }","duration":"351.444164ms","start":"2026-07-21T06:33:21.124564Z","end":"2026-07-21T06:33:21.476008Z","steps":["trace[135161117] 'read index received' (duration: 350.0027ms)","trace[135161117] 'applied index is now lower than readState.Index' (duration: 1.440584ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:21.476055Z","caller":"traceutil/trace.go:171","msg":"trace[532935482] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"459.520656ms","start":"2026-07-21T06:33:21.016522Z","end":"2026-07-21T06:33:21.476043Z","steps":["trace[532935482] 'process raft request' (duration: 459.181388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.476094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.016507Z","time spent":"459.569928ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:21.476111Z","caller":"traceutil/trace.go:171","msg":"trace[1323105649] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"450.251223ms","start":"2026-07-21T06:33:21.025827Z","end":"2026-07-21T06:33:21.476078Z","steps":["trace[1323105649] 'process raft request' (duration: 450.11484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.478442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.851313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-21T06:33:21.478524Z","caller":"traceutil/trace.go:171","msg":"trace[533501351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"353.983466ms","start":"2026-07-21T06:33:21.124521Z","end":"2026-07-21T06:33:21.478504Z","steps":["trace[533501351] 'agreement among raft nodes before linearized reading' (duration: 351.573577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.478566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.124502Z","time spent":"354.055718ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:33:21.478651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.025792Z","time spent":"450.389985ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:21.479013Z","caller":"traceutil/trace.go:171","msg":"trace[322523521] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"350.034ms","start":"2026-07-21T06:33:21.128969Z","end":"2026-07-21T06:33:21.479003Z","steps":["trace[322523521] 'process raft request' (duration: 349.898027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.480931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.128951Z","time spent":"351.929347ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:21.47916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.97939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:21.480997Z","caller":"traceutil/trace.go:171","msg":"trace[1828911708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"229.856005ms","start":"2026-07-21T06:33:21.251122Z","end":"2026-07-21T06:33:21.480978Z","steps":["trace[1828911708] 'agreement among raft nodes before linearized reading' (duration: 227.9861ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:21.479159Z","caller":"traceutil/trace.go:171","msg":"trace[482997633] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"339.542407ms","start":"2026-07-21T06:33:21.139597Z","end":"2026-07-21T06:33:21.479139Z","steps":["trace[482997633] 'process raft request' (duration: 339.374242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.481224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.13959Z","time spent":"341.598537ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:21.4792Z","caller":"traceutil/trace.go:171","msg":"trace[1193917051] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"334.247519ms","start":"2026-07-21T06:33:21.144942Z","end":"2026-07-21T06:33:21.479189Z","steps":["trace[1193917051] 'process raft request' (duration: 334.094065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.481322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.144927Z","time spent":"336.36383ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:21.479201Z","caller":"traceutil/trace.go:171","msg":"trace[621423536] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"344.415624ms","start":"2026-07-21T06:33:21.134777Z","end":"2026-07-21T06:33:21.479192Z","steps":["trace[621423536] 'process raft request' (duration: 344.154417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:21.481399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:21.134766Z","time spent":"346.610327ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:29.725258Z","caller":"traceutil/trace.go:171","msg":"trace[1696515568] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"202.029039ms","start":"2026-07-21T06:33:29.523209Z","end":"2026-07-21T06:33:29.725238Z","steps":["trace[1696515568] 'process raft request' (duration: 201.609445ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:33.922568Z","caller":"traceutil/trace.go:171","msg":"trace[1696438456] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"165.931181ms","start":"2026-07-21T06:33:33.756611Z","end":"2026-07-21T06:33:33.922542Z","steps":["trace[1696438456] 'process raft request' (duration: 165.743275ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:33.934847Z","caller":"traceutil/trace.go:171","msg":"trace[2113793680] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"158.789294ms","start":"2026-07-21T06:33:33.77603Z","end":"2026-07-21T06:33:33.934819Z","steps":["trace[2113793680] 'read index received' (duration: 146.395491ms)","trace[2113793680] 'applied index is now lower than readState.Index' (duration: 12.392143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:33.935033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.98624ms","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-07-21T06:33:33.935073Z","caller":"traceutil/trace.go:171","msg":"trace[1158366122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"159.075853ms","start":"2026-07-21T06:33:33.775986Z","end":"2026-07-21T06:33:33.935062Z","steps":["trace[1158366122] 'agreement among raft nodes before linearized reading' (duration: 158.930279ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:33.935191Z","caller":"traceutil/trace.go:171","msg":"trace[364219467] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"178.019054ms","start":"2026-07-21T06:33:33.757162Z","end":"2026-07-21T06:33:33.935181Z","steps":["trace[364219467] 'process raft request' (duration: 177.482317ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:35.8752Z","caller":"traceutil/trace.go:171","msg":"trace[256806307] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"511.603138ms","start":"2026-07-21T06:33:35.363571Z","end":"2026-07-21T06:33:35.875174Z","steps":["trace[256806307] 'process raft request' (duration: 511.464084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:35.875367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:35.363546Z","time spent":"511.729423ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:33:36.439601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830140053372568,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:33:36.921729Z","caller":"traceutil/trace.go:171","msg":"trace[918130906] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"982.852929ms","start":"2026-07-21T06:33:35.938852Z","end":"2026-07-21T06:33:36.921705Z","steps":["trace[918130906] 'read index received' (duration: 982.645132ms)","trace[918130906] 'applied index is now lower than readState.Index' (duration: 207.017µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:36.922028Z","caller":"traceutil/trace.go:171","msg":"trace[182175228] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"988.244257ms","start":"2026-07-21T06:33:35.933763Z","end":"2026-07-21T06:33:36.922007Z","steps":["trace[182175228] 'process raft request' (duration: 987.821381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:36.922312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:35.933746Z","time spent":"988.32347ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:33:36.922387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.284188ms","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-07-21T06:33:36.922453Z","caller":"traceutil/trace.go:171","msg":"trace[72405247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"973.366271ms","start":"2026-07-21T06:33:35.949071Z","end":"2026-07-21T06:33:36.922437Z","steps":["trace[72405247] 'agreement among raft nodes before linearized reading' (duration: 973.184375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:36.922486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:35.949038Z","time spent":"973.441524ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:33:36.922705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.737601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-21T06:33:36.922699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.328921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:36.922743Z","caller":"traceutil/trace.go:171","msg":"trace[1913822793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"722.779992ms","start":"2026-07-21T06:33:36.199955Z","end":"2026-07-21T06:33:36.922735Z","steps":["trace[1913822793] 'agreement among raft nodes before linearized reading' (duration: 722.673729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:36.922765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:36.19989Z","time spent":"722.869985ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T06:33:36.922777Z","caller":"traceutil/trace.go:171","msg":"trace[953601060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"672.448935ms","start":"2026-07-21T06:33:36.250307Z","end":"2026-07-21T06:33:36.922755Z","steps":["trace[953601060] 'agreement among raft nodes before linearized reading' (duration: 672.31169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:36.922821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:36.25029Z","time spent":"672.522958ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:33:36.922879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.995671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:33:36.922969Z","caller":"traceutil/trace.go:171","msg":"trace[672692374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"984.128174ms","start":"2026-07-21T06:33:35.938816Z","end":"2026-07-21T06:33:36.922945Z","steps":["trace[672692374] 'agreement among raft nodes before linearized reading' (duration: 983.832244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:36.923009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:35.938804Z","time spent":"984.198797ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:33:37.559213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.754954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:33:37.559319Z","caller":"traceutil/trace.go:171","msg":"trace[304385820] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"632.035042ms","start":"2026-07-21T06:33:36.927266Z","end":"2026-07-21T06:33:37.559302Z","steps":["trace[304385820] 'process raft request' (duration: 512.111321ms)","trace[304385820] 'compare' (duration: 119.62015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:37.559426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:36.927249Z","time spent":"632.147895ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:37.800139Z","caller":"traceutil/trace.go:171","msg":"trace[256881386] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"170.23015ms","start":"2026-07-21T06:33:37.629886Z","end":"2026-07-21T06:33:37.800116Z","steps":["trace[256881386] 'read index received' (duration: 169.843476ms)","trace[256881386] 'applied index is now lower than readState.Index' (duration: 385.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:37.800176Z","caller":"traceutil/trace.go:171","msg":"trace[207754761] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"872.6248ms","start":"2026-07-21T06:33:36.92752Z","end":"2026-07-21T06:33:37.800145Z","steps":["trace[207754761] 'process raft request' (duration: 872.214045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:37.800266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.357054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:33:37.800299Z","caller":"traceutil/trace.go:171","msg":"trace[2073959625] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:664; }","duration":"170.445827ms","start":"2026-07-21T06:33:37.629843Z","end":"2026-07-21T06:33:37.800289Z","steps":["trace[2073959625] 'agreement among raft nodes before linearized reading' (duration: 170.362074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:37.800304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:36.92746Z","time spent":"872.781316ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:37.800403Z","caller":"traceutil/trace.go:171","msg":"trace[1112145364] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"871.091587ms","start":"2026-07-21T06:33:36.929304Z","end":"2026-07-21T06:33:37.800396Z","steps":["trace[1112145364] 'process raft request' (duration: 870.768216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:37.800433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:36.929289Z","time spent":"871.127007ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:38.073269Z","caller":"traceutil/trace.go:171","msg":"trace[1333316947] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"144.600883ms","start":"2026-07-21T06:33:37.92864Z","end":"2026-07-21T06:33:38.073241Z","steps":["trace[1333316947] 'process raft request' (duration: 123.835523ms)","trace[1333316947] 'compare' (duration: 20.572603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:39.7158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.640612ms","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-07-21T06:33:39.715918Z","caller":"traceutil/trace.go:171","msg":"trace[1664484077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"152.807458ms","start":"2026-07-21T06:33:39.563084Z","end":"2026-07-21T06:33:39.715892Z","steps":["trace[1664484077] 'range keys from in-memory index tree' (duration: 152.379942ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:41.928978Z","caller":"traceutil/trace.go:171","msg":"trace[553358071] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"109.816682ms","start":"2026-07-21T06:33:41.819137Z","end":"2026-07-21T06:33:41.928954Z","steps":["trace[553358071] 'process raft request' (duration: 107.489373ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:42.543906Z","caller":"traceutil/trace.go:171","msg":"trace[212372160] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"100.126281ms","start":"2026-07-21T06:33:42.443751Z","end":"2026-07-21T06:33:42.543877Z","steps":["trace[212372160] 'read index received' (duration: 91.110259ms)","trace[212372160] 'applied index is now lower than readState.Index' (duration: 9.014852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:42.544096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-21T06:33:42.544079Z","caller":"traceutil/trace.go:171","msg":"trace[1648811517] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"131.800368ms","start":"2026-07-21T06:33:42.412245Z","end":"2026-07-21T06:33:42.544045Z","steps":["trace[1648811517] 'process raft request' (duration: 122.684872ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:42.544136Z","caller":"traceutil/trace.go:171","msg":"trace[1723711885] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:732; }","duration":"100.432203ms","start":"2026-07-21T06:33:42.443693Z","end":"2026-07-21T06:33:42.544126Z","steps":["trace[1723711885] 'agreement among raft nodes before linearized reading' (duration: 100.278657ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:44.284478Z","caller":"traceutil/trace.go:171","msg":"trace[553346568] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"102.831648ms","start":"2026-07-21T06:33:44.18163Z","end":"2026-07-21T06:33:44.284462Z","steps":["trace[553346568] 'process raft request' (duration: 92.91948ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:50.032092Z","caller":"traceutil/trace.go:171","msg":"trace[388800863] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"124.888978ms","start":"2026-07-21T06:33:49.907172Z","end":"2026-07-21T06:33:50.032061Z","steps":["trace[388800863] 'process raft request' (duration: 124.656447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:52.970556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.544174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tqzqt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-21T06:33:52.970633Z","caller":"traceutil/trace.go:171","msg":"trace[659777354] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tqzqt; range_end:; response_count:1; response_revision:818; }","duration":"515.688479ms","start":"2026-07-21T06:33:52.454927Z","end":"2026-07-21T06:33:52.970616Z","steps":["trace[659777354] 'range keys from in-memory index tree' (duration: 515.329055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:52.970677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:52.454907Z","time spent":"515.762123ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tqzqt\" "} {"level":"info","ts":"2026-07-21T06:34:25.757901Z","caller":"traceutil/trace.go:171","msg":"trace[1207984772] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"194.238487ms","start":"2026-07-21T06:34:25.563612Z","end":"2026-07-21T06:34:25.75785Z","steps":["trace[1207984772] 'process raft request' (duration: 194.039416ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:34:45.8947Z","caller":"traceutil/trace.go:171","msg":"trace[603015061] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"108.1719ms","start":"2026-07-21T06:34:45.7865Z","end":"2026-07-21T06:34:45.894672Z","steps":["trace[603015061] 'process raft request' (duration: 107.961807ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:34:46.47098Z","caller":"traceutil/trace.go:171","msg":"trace[130692492] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"129.553226ms","start":"2026-07-21T06:34:46.341404Z","end":"2026-07-21T06:34:46.470957Z","steps":["trace[130692492] 'process raft request' (duration: 98.13186ms)","trace[130692492] 'compare' (duration: 31.274987ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:34:56.95599Z","caller":"traceutil/trace.go:171","msg":"trace[1561259627] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"117.187421ms","start":"2026-07-21T06:34:56.838773Z","end":"2026-07-21T06:34:56.955961Z","steps":["trace[1561259627] 'process raft request' (duration: 117.004559ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:34:56.958966Z","caller":"traceutil/trace.go:171","msg":"trace[1771665145] linearizableReadLoop","detail":"{readStateIndex:1757; appliedIndex:1755; }","duration":"107.601777ms","start":"2026-07-21T06:34:56.851322Z","end":"2026-07-21T06:34:56.958924Z","steps":["trace[1771665145] 'read index received' (duration: 104.537016ms)","trace[1771665145] 'applied index is now lower than readState.Index' (duration: 3.063431ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:34:56.959083Z","caller":"traceutil/trace.go:171","msg":"trace[1479136723] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1692; }","duration":"118.508038ms","start":"2026-07-21T06:34:56.840521Z","end":"2026-07-21T06:34:56.95903Z","steps":["trace[1479136723] 'process raft request' (duration: 118.308705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:56.95921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.872554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:34:56.959266Z","caller":"traceutil/trace.go:171","msg":"trace[813974376] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1692; }","duration":"107.96924ms","start":"2026-07-21T06:34:56.85128Z","end":"2026-07-21T06:34:56.959249Z","steps":["trace[813974376] 'agreement among raft nodes before linearized reading' (duration: 107.844302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:56.959546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.684271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:34:56.959583Z","caller":"traceutil/trace.go:171","msg":"trace[1220499490] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1692; }","duration":"103.753836ms","start":"2026-07-21T06:34:56.85582Z","end":"2026-07-21T06:34:56.959574Z","steps":["trace[1220499490] 'agreement among raft nodes before linearized reading' (duration: 103.686311ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:01.861475Z","caller":"traceutil/trace.go:171","msg":"trace[1415583917] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"174.459313ms","start":"2026-07-21T06:35:01.68698Z","end":"2026-07-21T06:35:01.861439Z","steps":["trace[1415583917] 'process raft request' (duration: 145.907575ms)","trace[1415583917] 'compare' (duration: 28.216195ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:10.596893Z","caller":"traceutil/trace.go:171","msg":"trace[1622688169] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"106.657294ms","start":"2026-07-21T06:35:10.490186Z","end":"2026-07-21T06:35:10.596843Z","steps":["trace[1622688169] 'process raft request' (duration: 54.440046ms)","trace[1622688169] 'compare' (duration: 51.932508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:10.59696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.053458ms","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-07-21T06:35:10.597109Z","caller":"traceutil/trace.go:171","msg":"trace[770866486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"100.261852ms","start":"2026-07-21T06:35:10.496817Z","end":"2026-07-21T06:35:10.597079Z","steps":["trace[770866486] 'agreement among raft nodes before linearized reading' (duration: 99.908819ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:11.420336Z","caller":"traceutil/trace.go:171","msg":"trace[1622062690] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"421.439439ms","start":"2026-07-21T06:35:10.998863Z","end":"2026-07-21T06:35:11.420303Z","steps":["trace[1622062690] 'process raft request' (duration: 421.30092ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:11.420387Z","caller":"traceutil/trace.go:171","msg":"trace[1309412253] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1962; }","duration":"329.813147ms","start":"2026-07-21T06:35:11.090541Z","end":"2026-07-21T06:35:11.420354Z","steps":["trace[1309412253] 'read index received' (duration: 329.803807ms)","trace[1309412253] 'applied index is now lower than readState.Index' (duration: 7.29µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:11.420557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.991939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b486b8a-1ff2-4837-b4d6-f09e6b13305e\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-07-21T06:35:11.420569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:10.998839Z","time spent":"421.60324ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:11.420602Z","caller":"traceutil/trace.go:171","msg":"trace[955597271] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b486b8a-1ff2-4837-b4d6-f09e6b13305e; range_end:; response_count:1; response_revision:1890; }","duration":"330.089286ms","start":"2026-07-21T06:35:11.090501Z","end":"2026-07-21T06:35:11.42059Z","steps":["trace[955597271] 'agreement among raft nodes before linearized reading' (duration: 329.961037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:11.420643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:11.090487Z","time spent":"330.147031ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b486b8a-1ff2-4837-b4d6-f09e6b13305e\" "} {"level":"info","ts":"2026-07-21T06:35:11.635652Z","caller":"traceutil/trace.go:171","msg":"trace[691294710] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"180.114974ms","start":"2026-07-21T06:35:11.45551Z","end":"2026-07-21T06:35:11.635625Z","steps":["trace[691294710] 'process raft request' (duration: 138.604523ms)","trace[691294710] 'compare' (duration: 41.398183ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:24.377756Z","caller":"traceutil/trace.go:171","msg":"trace[458470623] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"201.631119ms","start":"2026-07-21T06:35:24.17611Z","end":"2026-07-21T06:35:24.377741Z","steps":["trace[458470623] 'read index received' (duration: 201.486709ms)","trace[458470623] 'applied index is now lower than readState.Index' (duration: 143.86µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:24.377842Z","caller":"traceutil/trace.go:171","msg":"trace[1287191578] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"201.767538ms","start":"2026-07-21T06:35:24.176032Z","end":"2026-07-21T06:35:24.377799Z","steps":["trace[1287191578] 'process raft request' (duration: 201.611257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:24.377927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.150387ms","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-07-21T06:35:24.377961Z","caller":"traceutil/trace.go:171","msg":"trace[611799445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2002; }","duration":"129.233583ms","start":"2026-07-21T06:35:24.248719Z","end":"2026-07-21T06:35:24.377953Z","steps":["trace[611799445] 'agreement among raft nodes before linearized reading' (duration: 129.119696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:24.377968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.057521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:35:24.377931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.812062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-21T06:35:24.378028Z","caller":"traceutil/trace.go:171","msg":"trace[1934242846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2002; }","duration":"128.133526ms","start":"2026-07-21T06:35:24.249881Z","end":"2026-07-21T06:35:24.378015Z","steps":["trace[1934242846] 'agreement among raft nodes before linearized reading' (duration: 128.046041ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:24.378028Z","caller":"traceutil/trace.go:171","msg":"trace[1926747547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2002; }","duration":"201.964603ms","start":"2026-07-21T06:35:24.176052Z","end":"2026-07-21T06:35:24.378016Z","steps":["trace[1926747547] 'agreement among raft nodes before linearized reading' (duration: 201.763399ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:30.546483Z","caller":"traceutil/trace.go:171","msg":"trace[2145335069] linearizableReadLoop","detail":"{readStateIndex:2129; appliedIndex:2128; }","duration":"140.025898ms","start":"2026-07-21T06:35:30.406431Z","end":"2026-07-21T06:35:30.546457Z","steps":["trace[2145335069] 'read index received' (duration: 139.76662ms)","trace[2145335069] 'applied index is now lower than readState.Index' (duration: 258.378µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:30.546616Z","caller":"traceutil/trace.go:171","msg":"trace[514016160] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"140.321098ms","start":"2026-07-21T06:35:30.406254Z","end":"2026-07-21T06:35:30.546575Z","steps":["trace[514016160] 'process raft request' (duration: 140.025668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:30.546767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.308719ms","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-07-21T06:35:30.546835Z","caller":"traceutil/trace.go:171","msg":"trace[1011781837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2053; }","duration":"140.412526ms","start":"2026-07-21T06:35:30.406403Z","end":"2026-07-21T06:35:30.546815Z","steps":["trace[1011781837] 'agreement among raft nodes before linearized reading' (duration: 140.171938ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:30.548487Z","caller":"traceutil/trace.go:171","msg":"trace[517076647] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"139.671943ms","start":"2026-07-21T06:35:30.408796Z","end":"2026-07-21T06:35:30.548468Z","steps":["trace[517076647] 'process raft request' (duration: 139.565985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:30.999005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.969795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:35:30.999112Z","caller":"traceutil/trace.go:171","msg":"trace[2090425472] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"215.556744ms","start":"2026-07-21T06:35:30.783536Z","end":"2026-07-21T06:35:30.999093Z","steps":["trace[2090425472] 'process raft request' (duration: 68.407196ms)","trace[2090425472] 'compare' (duration: 146.781762ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:31.032926Z","caller":"traceutil/trace.go:171","msg":"trace[1196997945] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"218.551877ms","start":"2026-07-21T06:35:30.814348Z","end":"2026-07-21T06:35:31.032899Z","steps":["trace[1196997945] 'process raft request' (duration: 218.435699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:31.366289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.678942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-21T06:35:31.366422Z","caller":"traceutil/trace.go:171","msg":"trace[766273062] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2059; }","duration":"241.883825ms","start":"2026-07-21T06:35:31.12451Z","end":"2026-07-21T06:35:31.366394Z","steps":["trace[766273062] 'range keys from in-memory index tree' (duration: 241.385381ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:41.164345Z","caller":"traceutil/trace.go:171","msg":"trace[63306228] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"122.406149ms","start":"2026-07-21T06:35:41.041907Z","end":"2026-07-21T06:35:41.164313Z","steps":["trace[63306228] 'process raft request' (duration: 122.185043ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:45.281709Z","caller":"traceutil/trace.go:171","msg":"trace[1323416225] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"100.764323ms","start":"2026-07-21T06:35:45.180915Z","end":"2026-07-21T06:35:45.281679Z","steps":["trace[1323416225] 'process raft request' (duration: 100.58595ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:45.412653Z","caller":"traceutil/trace.go:171","msg":"trace[1499464572] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"126.818577ms","start":"2026-07-21T06:35:45.285797Z","end":"2026-07-21T06:35:45.412615Z","steps":["trace[1499464572] 'process raft request' (duration: 82.188203ms)","trace[1499464572] 'compare' (duration: 44.327932ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:46.248891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.592751ms","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-07-21T06:35:46.248965Z","caller":"traceutil/trace.go:171","msg":"trace[1599558148] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2133; }","duration":"126.702379ms","start":"2026-07-21T06:35:46.122242Z","end":"2026-07-21T06:35:46.248945Z","steps":["trace[1599558148] 'range keys from in-memory index tree' (duration: 126.45187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.249238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.701955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129701"} {"level":"info","ts":"2026-07-21T06:35:46.249309Z","caller":"traceutil/trace.go:171","msg":"trace[606829020] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2133; }","duration":"237.816842ms","start":"2026-07-21T06:35:46.011472Z","end":"2026-07-21T06:35:46.249289Z","steps":["trace[606829020] 'range keys from in-memory index tree' (duration: 237.280674ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:46.65066Z","caller":"traceutil/trace.go:171","msg":"trace[1424099316] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"395.826665ms","start":"2026-07-21T06:35:46.254807Z","end":"2026-07-21T06:35:46.650634Z","steps":["trace[1424099316] 'read index received' (duration: 395.673514ms)","trace[1424099316] 'applied index is now lower than readState.Index' (duration: 152.121µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:46.650733Z","caller":"traceutil/trace.go:171","msg":"trace[1470872592] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"396.17942ms","start":"2026-07-21T06:35:46.254519Z","end":"2026-07-21T06:35:46.650698Z","steps":["trace[1470872592] 'process raft request' (duration: 395.936593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.650894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.068552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129701"} {"level":"info","ts":"2026-07-21T06:35:46.650939Z","caller":"traceutil/trace.go:171","msg":"trace[1150610193] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2134; }","duration":"396.164709ms","start":"2026-07-21T06:35:46.254763Z","end":"2026-07-21T06:35:46.650928Z","steps":["trace[1150610193] 'agreement among raft nodes before linearized reading' (duration: 395.993496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.650943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.254491Z","time spent":"396.355483ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:46.650966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.254749Z","time spent":"396.211393ms","remote":"127.0.0.1:43410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129724,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-21T06:35:46.661274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.70968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:46.661347Z","caller":"traceutil/trace.go:171","msg":"trace[698520694] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2135; }","duration":"379.823648ms","start":"2026-07-21T06:35:46.281506Z","end":"2026-07-21T06:35:46.66133Z","steps":["trace[698520694] 'agreement among raft nodes before linearized reading' (duration: 379.647195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.661395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.281488Z","time spent":"379.894132ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:35:46.661697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.128262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-21T06:35:46.661724Z","caller":"traceutil/trace.go:171","msg":"trace[1723573980] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2135; }","duration":"188.156844ms","start":"2026-07-21T06:35:46.473559Z","end":"2026-07-21T06:35:46.661716Z","steps":["trace[1723573980] 'agreement among raft nodes before linearized reading' (duration: 188.064017ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:46.661762Z","caller":"traceutil/trace.go:171","msg":"trace[30518080] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"272.108479ms","start":"2026-07-21T06:35:46.38956Z","end":"2026-07-21T06:35:46.661668Z","steps":["trace[30518080] 'process raft request' (duration: 271.474904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.146118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.369937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:35:47.146604Z","caller":"traceutil/trace.go:171","msg":"trace[2080801805] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"478.163067ms","start":"2026-07-21T06:35:46.668416Z","end":"2026-07-21T06:35:47.146579Z","steps":["trace[2080801805] 'process raft request' (duration: 315.23588ms)","trace[2080801805] 'compare' (duration: 161.894713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:47.146696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.668401Z","time spent":"478.251705ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:47.146802Z","caller":"traceutil/trace.go:171","msg":"trace[384092295] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"456.242367ms","start":"2026-07-21T06:35:46.690403Z","end":"2026-07-21T06:35:47.146645Z","steps":["trace[384092295] 'read index received' (duration: 39.188189ms)","trace[384092295] 'applied index is now lower than readState.Index' (duration: 417.051778ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:47.147165Z","caller":"traceutil/trace.go:171","msg":"trace[1293195416] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"356.694243ms","start":"2026-07-21T06:35:46.790454Z","end":"2026-07-21T06:35:47.147149Z","steps":["trace[1293195416] 'process raft request' (duration: 355.81354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.147269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.790431Z","time spent":"356.779848ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:47.147328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.884662ms","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-07-21T06:35:47.147364Z","caller":"traceutil/trace.go:171","msg":"trace[1815591749] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"311.878812ms","start":"2026-07-21T06:35:46.835476Z","end":"2026-07-21T06:35:47.147354Z","steps":["trace[1815591749] 'process raft request' (duration: 311.615643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.147393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.186717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-21T06:35:47.147439Z","caller":"traceutil/trace.go:171","msg":"trace[1676254702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2137; }","duration":"457.029073ms","start":"2026-07-21T06:35:46.69037Z","end":"2026-07-21T06:35:47.147399Z","steps":["trace[1676254702] 'agreement among raft nodes before linearized reading' (duration: 456.382027ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:47.147455Z","caller":"traceutil/trace.go:171","msg":"trace[1477793089] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2139; }","duration":"374.277324ms","start":"2026-07-21T06:35:46.773168Z","end":"2026-07-21T06:35:47.147446Z","steps":["trace[1477793089] 'agreement among raft nodes before linearized reading' (duration: 374.023135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.147637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.690357Z","time spent":"457.234878ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:35:47.147624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.773114Z","time spent":"374.498809ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-21T06:35:47.147765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.835452Z","time spent":"311.955548ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:47.457477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.229617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:47.457589Z","caller":"traceutil/trace.go:171","msg":"trace[630674767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"207.442624ms","start":"2026-07-21T06:35:47.250132Z","end":"2026-07-21T06:35:47.457575Z","steps":["trace[630674767] 'range keys from in-memory index tree' (duration: 207.146451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.457537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.006011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:35:47.457746Z","caller":"traceutil/trace.go:171","msg":"trace[1381452803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2141; }","duration":"172.26649ms","start":"2026-07-21T06:35:47.285461Z","end":"2026-07-21T06:35:47.457727Z","steps":["trace[1381452803] 'range keys from in-memory index tree' (duration: 171.831117ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:47.59141Z","caller":"traceutil/trace.go:171","msg":"trace[722376551] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"129.168548ms","start":"2026-07-21T06:35:47.462223Z","end":"2026-07-21T06:35:47.591391Z","steps":["trace[722376551] 'process raft request' (duration: 103.577832ms)","trace[722376551] 'compare' (duration: 25.316886ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:48.098885Z","caller":"traceutil/trace.go:171","msg":"trace[881676584] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"430.031161ms","start":"2026-07-21T06:35:47.668824Z","end":"2026-07-21T06:35:48.098855Z","steps":["trace[881676584] 'read index received' (duration: 395.590817ms)","trace[881676584] 'applied index is now lower than readState.Index' (duration: 34.438824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:48.099009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:47.636177Z","time spent":"462.829174ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T06:35:48.099355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.510175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-21T06:35:48.099398Z","caller":"traceutil/trace.go:171","msg":"trace[1079639723] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"430.593171ms","start":"2026-07-21T06:35:47.668789Z","end":"2026-07-21T06:35:48.099382Z","steps":["trace[1079639723] 'agreement among raft nodes before linearized reading' (duration: 430.442361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.099428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:47.66877Z","time spent":"430.651755ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-21T06:35:48.099567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.664419ms","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-07-21T06:35:48.099646Z","caller":"traceutil/trace.go:171","msg":"trace[408154858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2144; }","duration":"150.800438ms","start":"2026-07-21T06:35:47.948828Z","end":"2026-07-21T06:35:48.099628Z","steps":["trace[408154858] 'agreement among raft nodes before linearized reading' (duration: 150.503196ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:48.326136Z","caller":"traceutil/trace.go:171","msg":"trace[132076262] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"222.062501ms","start":"2026-07-21T06:35:48.104047Z","end":"2026-07-21T06:35:48.326109Z","steps":["trace[132076262] 'process raft request' (duration: 221.939002ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:48.326291Z","caller":"traceutil/trace.go:171","msg":"trace[1645713375] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"225.777087ms","start":"2026-07-21T06:35:48.100495Z","end":"2026-07-21T06:35:48.326272Z","steps":["trace[1645713375] 'process raft request' (duration: 140.508603ms)","trace[1645713375] 'compare' (duration: 84.835044ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:48.799031Z","caller":"traceutil/trace.go:171","msg":"trace[113144440] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"124.008522ms","start":"2026-07-21T06:36:48.674983Z","end":"2026-07-21T06:36:48.798992Z","steps":["trace[113144440] 'process raft request' (duration: 80.816933ms)","trace[113144440] 'compare' (duration: 43.060704ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:48.804572Z","caller":"traceutil/trace.go:171","msg":"trace[687576365] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"129.339686ms","start":"2026-07-21T06:36:48.675202Z","end":"2026-07-21T06:36:48.804542Z","steps":["trace[687576365] 'process raft request' (duration: 129.139224ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:48.804659Z","caller":"traceutil/trace.go:171","msg":"trace[603865699] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"129.148194ms","start":"2026-07-21T06:36:48.675493Z","end":"2026-07-21T06:36:48.804641Z","steps":["trace[603865699] 'process raft request' (duration: 128.97322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:49.147285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.55293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:36:49.147373Z","caller":"traceutil/trace.go:171","msg":"trace[103185896] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"140.446971ms","start":"2026-07-21T06:36:49.006912Z","end":"2026-07-21T06:36:49.147359Z","steps":["trace[103185896] 'read index received' (duration: 39.769895ms)","trace[103185896] 'applied index is now lower than readState.Index' (duration: 100.676056ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:49.147521Z","caller":"traceutil/trace.go:171","msg":"trace[924246370] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"287.623689ms","start":"2026-07-21T06:36:48.859888Z","end":"2026-07-21T06:36:49.147512Z","steps":["trace[924246370] 'process raft request' (duration: 186.77523ms)","trace[924246370] 'compare' (duration: 100.360207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:49.148204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.265811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T06:36:49.148281Z","caller":"traceutil/trace.go:171","msg":"trace[63555695] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2545; }","duration":"141.391157ms","start":"2026-07-21T06:36:49.006871Z","end":"2026-07-21T06:36:49.148262Z","steps":["trace[63555695] 'agreement among raft nodes before linearized reading' (duration: 141.04676ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.386384Z","caller":"traceutil/trace.go:171","msg":"trace[788667050] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"232.392457ms","start":"2026-07-21T06:36:49.153964Z","end":"2026-07-21T06:36:49.386356Z","steps":["trace[788667050] 'process raft request' (duration: 232.337429ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.386463Z","caller":"traceutil/trace.go:171","msg":"trace[1950481908] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"233.546657ms","start":"2026-07-21T06:36:49.152874Z","end":"2026-07-21T06:36:49.386421Z","steps":["trace[1950481908] 'process raft request' (duration: 151.094369ms)","trace[1950481908] 'compare' (duration: 82.062331ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:49.386618Z","caller":"traceutil/trace.go:171","msg":"trace[107456353] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"233.414531ms","start":"2026-07-21T06:36:49.153148Z","end":"2026-07-21T06:36:49.386563Z","steps":["trace[107456353] 'process raft request' (duration: 233.089962ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.657799Z","caller":"traceutil/trace.go:171","msg":"trace[971924302] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"162.228421ms","start":"2026-07-21T06:36:49.495545Z","end":"2026-07-21T06:36:49.657773Z","steps":["trace[971924302] 'process raft request' (duration: 161.575714ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.671097Z","caller":"traceutil/trace.go:171","msg":"trace[1725125480] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"174.710504ms","start":"2026-07-21T06:36:49.496369Z","end":"2026-07-21T06:36:49.671079Z","steps":["trace[1725125480] 'process raft request' (duration: 174.573929ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.671378Z","caller":"traceutil/trace.go:171","msg":"trace[553750871] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"174.927626ms","start":"2026-07-21T06:36:49.496432Z","end":"2026-07-21T06:36:49.671359Z","steps":["trace[553750871] 'process raft request' (duration: 174.589568ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.671566Z","caller":"traceutil/trace.go:171","msg":"trace[632112156] linearizableReadLoop","detail":"{readStateIndex:2652; appliedIndex:2647; }","duration":"101.228975ms","start":"2026-07-21T06:36:49.570318Z","end":"2026-07-21T06:36:49.671547Z","steps":["trace[632112156] 'read index received' (duration: 86.907677ms)","trace[632112156] 'applied index is now lower than readState.Index' (duration: 14.320268ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:49.671663Z","caller":"traceutil/trace.go:171","msg":"trace[822228833] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"175.07847ms","start":"2026-07-21T06:36:49.49657Z","end":"2026-07-21T06:36:49.671648Z","steps":["trace[822228833] 'process raft request' (duration: 174.900347ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.6717Z","caller":"traceutil/trace.go:171","msg":"trace[1801333004] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"175.265444ms","start":"2026-07-21T06:36:49.496422Z","end":"2026-07-21T06:36:49.671687Z","steps":["trace[1801333004] 'process raft request' (duration: 174.81674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:49.672019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.685199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:36:49.672081Z","caller":"traceutil/trace.go:171","msg":"trace[1494018521] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2558; }","duration":"101.808235ms","start":"2026-07-21T06:36:49.570255Z","end":"2026-07-21T06:36:49.672063Z","steps":["trace[1494018521] 'agreement among raft nodes before linearized reading' (duration: 101.617911ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:49.91014Z","caller":"traceutil/trace.go:171","msg":"trace[424893904] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"182.468286ms","start":"2026-07-21T06:36:49.727645Z","end":"2026-07-21T06:36:49.910113Z","steps":["trace[424893904] 'process raft request' (duration: 113.048711ms)","trace[424893904] 'compare' (duration: 68.649693ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:49.911244Z","caller":"traceutil/trace.go:171","msg":"trace[1785800650] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"179.760152ms","start":"2026-07-21T06:36:49.731468Z","end":"2026-07-21T06:36:49.911228Z","steps":["trace[1785800650] 'process raft request' (duration: 178.43356ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:50.15291Z","caller":"traceutil/trace.go:171","msg":"trace[101094304] linearizableReadLoop","detail":"{readStateIndex:2662; appliedIndex:2660; }","duration":"221.576142ms","start":"2026-07-21T06:36:49.931315Z","end":"2026-07-21T06:36:50.152891Z","steps":["trace[101094304] 'read index received' (duration: 209.989018ms)","trace[101094304] 'applied index is now lower than readState.Index' (duration: 11.586364ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:50.15308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.747935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-21T06:36:50.153114Z","caller":"traceutil/trace.go:171","msg":"trace[1992573776] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2569; }","duration":"221.814494ms","start":"2026-07-21T06:36:49.931289Z","end":"2026-07-21T06:36:50.153104Z","steps":["trace[1992573776] 'agreement among raft nodes before linearized reading' (duration: 221.680159ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:50.153123Z","caller":"traceutil/trace.go:171","msg":"trace[2098801878] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"229.502518ms","start":"2026-07-21T06:36:49.923594Z","end":"2026-07-21T06:36:50.153097Z","steps":["trace[2098801878] 'process raft request' (duration: 217.714341ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:50.153463Z","caller":"traceutil/trace.go:171","msg":"trace[102399646] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"228.90708ms","start":"2026-07-21T06:36:49.924545Z","end":"2026-07-21T06:36:50.153453Z","steps":["trace[102399646] 'process raft request' (duration: 228.217314ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:50.153681Z","caller":"traceutil/trace.go:171","msg":"trace[1974890309] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"220.713514ms","start":"2026-07-21T06:36:49.932958Z","end":"2026-07-21T06:36:50.153672Z","steps":["trace[1974890309] 'process raft request' (duration: 219.897932ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:50.324375Z","caller":"traceutil/trace.go:171","msg":"trace[888119657] linearizableReadLoop","detail":"{readStateIndex:2664; appliedIndex:2663; }","duration":"142.780717ms","start":"2026-07-21T06:36:50.181573Z","end":"2026-07-21T06:36:50.324354Z","steps":["trace[888119657] 'read index received' (duration: 141.731265ms)","trace[888119657] 'applied index is now lower than readState.Index' (duration: 1.048362ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:50.324668Z","caller":"traceutil/trace.go:171","msg":"trace[1221661698] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"144.650532ms","start":"2026-07-21T06:36:50.180004Z","end":"2026-07-21T06:36:50.324654Z","steps":["trace[1221661698] 'process raft request' (duration: 143.434115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:50.325078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.485863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7086"} {"level":"info","ts":"2026-07-21T06:36:50.325124Z","caller":"traceutil/trace.go:171","msg":"trace[1306746577] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2570; }","duration":"143.57965ms","start":"2026-07-21T06:36:50.181534Z","end":"2026-07-21T06:36:50.325114Z","steps":["trace[1306746577] 'agreement among raft nodes before linearized reading' (duration: 143.398336ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:52.416853Z","caller":"traceutil/trace.go:171","msg":"trace[1161287248] linearizableReadLoop","detail":"{readStateIndex:2683; appliedIndex:2682; }","duration":"239.65126ms","start":"2026-07-21T06:36:52.17718Z","end":"2026-07-21T06:36:52.416831Z","steps":["trace[1161287248] 'read index received' (duration: 239.539554ms)","trace[1161287248] 'applied index is now lower than readState.Index' (duration: 110.796µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:52.416975Z","caller":"traceutil/trace.go:171","msg":"trace[977823568] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"255.995134ms","start":"2026-07-21T06:36:52.16097Z","end":"2026-07-21T06:36:52.416965Z","steps":["trace[977823568] 'process raft request' (duration: 255.726642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:52.417075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.881063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:36:52.417124Z","caller":"traceutil/trace.go:171","msg":"trace[928635304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2588; }","duration":"239.954861ms","start":"2026-07-21T06:36:52.177153Z","end":"2026-07-21T06:36:52.417108Z","steps":["trace[928635304] 'agreement among raft nodes before linearized reading' (duration: 239.835245ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:52.516173Z","caller":"traceutil/trace.go:171","msg":"trace[38652322] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"162.736697ms","start":"2026-07-21T06:36:52.353411Z","end":"2026-07-21T06:36:52.516148Z","steps":["trace[38652322] 'process raft request' (duration: 162.04328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:52.5163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.658526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-21T06:36:52.516361Z","caller":"traceutil/trace.go:171","msg":"trace[1239317572] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2589; }","duration":"180.755245ms","start":"2026-07-21T06:36:52.335592Z","end":"2026-07-21T06:36:52.516348Z","steps":["trace[1239317572] 'agreement among raft nodes before linearized reading' (duration: 180.56593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:52.516432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.546372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T06:36:52.516507Z","caller":"traceutil/trace.go:171","msg":"trace[1039956655] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2589; }","duration":"194.654168ms","start":"2026-07-21T06:36:52.321833Z","end":"2026-07-21T06:36:52.516487Z","steps":["trace[1039956655] 'agreement among raft nodes before linearized reading' (duration: 194.457444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:52.516759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.874855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:52.516805Z","caller":"traceutil/trace.go:171","msg":"trace[1765628401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2589; }","duration":"267.947782ms","start":"2026-07-21T06:36:52.248845Z","end":"2026-07-21T06:36:52.516793Z","steps":["trace[1765628401] 'agreement among raft nodes before linearized reading' (duration: 267.278894ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:52.541389Z","caller":"traceutil/trace.go:171","msg":"trace[268426516] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"119.68294ms","start":"2026-07-21T06:36:52.421647Z","end":"2026-07-21T06:36:52.541329Z","steps":["trace[268426516] 'process raft request' (duration: 118.939105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.751683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830140053376664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:36:53.788355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171604441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T06:36:53.788409Z","caller":"traceutil/trace.go:171","msg":"trace[851308016] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2590; }","duration":"1.171678688s","start":"2026-07-21T06:36:52.616719Z","end":"2026-07-21T06:36:53.788398Z","steps":["trace[851308016] 'range keys from in-memory index tree' (duration: 1.171523892s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.788429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:52.61671Z","time spent":"1.171714687s","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T06:36:53.788606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034504935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:53.788632Z","caller":"traceutil/trace.go:171","msg":"trace[2007678572] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2590; }","duration":"1.034572322s","start":"2026-07-21T06:36:52.754053Z","end":"2026-07-21T06:36:53.788625Z","steps":["trace[2007678572] 'count revisions from in-memory index tree' (duration: 1.034319601s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.788651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:52.754012Z","time spent":"1.03463226s","remote":"127.0.0.1:58810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:36:53.788824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.512681ms","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-07-21T06:36:53.788843Z","caller":"traceutil/trace.go:171","msg":"trace[1822718865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2590; }","duration":"972.557739ms","start":"2026-07-21T06:36:52.816282Z","end":"2026-07-21T06:36:53.788839Z","steps":["trace[1822718865] 'range keys from in-memory index tree' (duration: 972.374006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.788855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:52.816266Z","time spent":"972.586009ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:36:53.788937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.121197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:36:53.788923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.430922ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:53.788989Z","caller":"traceutil/trace.go:171","msg":"trace[1318865403] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2590; }","duration":"234.51277ms","start":"2026-07-21T06:36:53.554461Z","end":"2026-07-21T06:36:53.788974Z","steps":["trace[1318865403] 'range keys from in-memory index tree' (duration: 234.415763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:53.788956Z","caller":"traceutil/trace.go:171","msg":"trace[2105750309] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2590; }","duration":"951.198454ms","start":"2026-07-21T06:36:52.837753Z","end":"2026-07-21T06:36:53.788952Z","steps":["trace[2105750309] 'count revisions from in-memory index tree' (duration: 950.962573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.789225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:52.837724Z","time spent":"951.482276ms","remote":"127.0.0.1:58234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:36:53.806286Z","caller":"traceutil/trace.go:171","msg":"trace[670002763] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"350.746004ms","start":"2026-07-21T06:36:53.455523Z","end":"2026-07-21T06:36:53.806269Z","steps":["trace[670002763] 'process raft request' (duration: 350.533121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.8064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:53.455496Z","time spent":"350.842621ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:36:53.806521Z","caller":"traceutil/trace.go:171","msg":"trace[1008702281] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"349.548683ms","start":"2026-07-21T06:36:53.456957Z","end":"2026-07-21T06:36:53.806506Z","steps":["trace[1008702281] 'process raft request' (duration: 349.265962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.806612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:53.456921Z","time spent":"349.635981ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:36:53.80682Z","caller":"traceutil/trace.go:171","msg":"trace[466607179] linearizableReadLoop","detail":"{readStateIndex:2688; appliedIndex:2685; }","duration":"556.040841ms","start":"2026-07-21T06:36:53.250758Z","end":"2026-07-21T06:36:53.806799Z","steps":["trace[466607179] 'read index received' (duration: 538.65846ms)","trace[466607179] 'applied index is now lower than readState.Index' (duration: 17.381061ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:53.807224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.460476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:53.807297Z","caller":"traceutil/trace.go:171","msg":"trace[915425893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2593; }","duration":"556.568423ms","start":"2026-07-21T06:36:53.25071Z","end":"2026-07-21T06:36:53.807278Z","steps":["trace[915425893] 'agreement among raft nodes before linearized reading' (duration: 556.461166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.807358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:53.250692Z","time spent":"556.6498ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:36:53.874083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.627162ms","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-07-21T06:36:53.874148Z","caller":"traceutil/trace.go:171","msg":"trace[1641999114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2594; }","duration":"472.734848ms","start":"2026-07-21T06:36:53.401398Z","end":"2026-07-21T06:36:53.874133Z","steps":["trace[1641999114] 'agreement among raft nodes before linearized reading' (duration: 472.572864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.874197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:53.40138Z","time spent":"472.809355ms","remote":"127.0.0.1:43630","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":"warn","ts":"2026-07-21T06:36:53.874084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.965965ms","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-07-21T06:36:53.874284Z","caller":"traceutil/trace.go:171","msg":"trace[45800971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2594; }","duration":"166.224025ms","start":"2026-07-21T06:36:53.708054Z","end":"2026-07-21T06:36:53.874278Z","steps":["trace[45800971] 'agreement among raft nodes before linearized reading' (duration: 165.879287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.874083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.347644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T06:36:53.874348Z","caller":"traceutil/trace.go:171","msg":"trace[1662447684] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2594; }","duration":"471.648873ms","start":"2026-07-21T06:36:53.402693Z","end":"2026-07-21T06:36:53.874342Z","steps":["trace[1662447684] 'agreement among raft nodes before linearized reading' (duration: 471.301635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:53.874375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:53.402686Z","time spent":"471.682162ms","remote":"127.0.0.1:43630","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":"warn","ts":"2026-07-21T06:36:53.874582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.860571ms","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-07-21T06:36:53.874609Z","caller":"traceutil/trace.go:171","msg":"trace[337262285] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2594; }","duration":"164.88979ms","start":"2026-07-21T06:36:53.709714Z","end":"2026-07-21T06:36:53.874603Z","steps":["trace[337262285] 'agreement among raft nodes before linearized reading' (duration: 164.213462ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:37:00.598641Z","caller":"traceutil/trace.go:171","msg":"trace[180619313] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"133.759447ms","start":"2026-07-21T06:37:00.464853Z","end":"2026-07-21T06:37:00.598613Z","steps":["trace[180619313] 'process raft request' (duration: 133.490294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:29.402729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.76675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:37:29.402955Z","caller":"traceutil/trace.go:171","msg":"trace[870433519] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"627.008555ms","start":"2026-07-21T06:37:28.775925Z","end":"2026-07-21T06:37:29.402933Z","steps":["trace[870433519] 'process raft request' (duration: 314.942689ms)","trace[870433519] 'compare' (duration: 311.549561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:37:29.40312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:37:28.775903Z","time spent":"627.169704ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:37:42.388746Z","caller":"traceutil/trace.go:171","msg":"trace[1965687609] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"182.957547ms","start":"2026-07-21T06:37:42.205755Z","end":"2026-07-21T06:37:42.388713Z","steps":["trace[1965687609] 'process raft request' (duration: 88.216576ms)","trace[1965687609] 'compare' (duration: 94.459252ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:42.388953Z","caller":"traceutil/trace.go:171","msg":"trace[1435142887] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"182.007099ms","start":"2026-07-21T06:37:42.206924Z","end":"2026-07-21T06:37:42.388932Z","steps":["trace[1435142887] 'process raft request' (duration: 181.701079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:42.389042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.074773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:37:42.389131Z","caller":"traceutil/trace.go:171","msg":"trace[207149775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2859; }","duration":"142.206133ms","start":"2026-07-21T06:37:42.246903Z","end":"2026-07-21T06:37:42.389109Z","steps":["trace[207149775] 'agreement among raft nodes before linearized reading' (duration: 142.064203ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:37:42.388739Z","caller":"traceutil/trace.go:171","msg":"trace[1381921974] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2962; }","duration":"141.763313ms","start":"2026-07-21T06:37:42.246945Z","end":"2026-07-21T06:37:42.388708Z","steps":["trace[1381921974] 'read index received' (duration: 46.963572ms)","trace[1381921974] 'applied index is now lower than readState.Index' (duration: 94.798121ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:50.472774Z","caller":"traceutil/trace.go:171","msg":"trace[1461473086] linearizableReadLoop","detail":"{readStateIndex:3008; appliedIndex:3006; }","duration":"137.846371ms","start":"2026-07-21T06:37:50.334914Z","end":"2026-07-21T06:37:50.47276Z","steps":["trace[1461473086] 'read index received' (duration: 72.692608ms)","trace[1461473086] 'applied index is now lower than readState.Index' (duration: 65.153023ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:50.472807Z","caller":"traceutil/trace.go:171","msg":"trace[1435839075] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"150.559134ms","start":"2026-07-21T06:37:50.322222Z","end":"2026-07-21T06:37:50.472782Z","steps":["trace[1435839075] 'process raft request' (duration: 85.374291ms)","trace[1435839075] 'compare' (duration: 65.054252ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:50.472853Z","caller":"traceutil/trace.go:171","msg":"trace[1556702161] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"149.297163ms","start":"2026-07-21T06:37:50.323548Z","end":"2026-07-21T06:37:50.472845Z","steps":["trace[1556702161] 'process raft request' (duration: 149.186363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:50.473009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.095351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-21T06:37:50.473036Z","caller":"traceutil/trace.go:171","msg":"trace[1440032415] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2901; }","duration":"138.178411ms","start":"2026-07-21T06:37:50.334851Z","end":"2026-07-21T06:37:50.47303Z","steps":["trace[1440032415] 'agreement among raft nodes before linearized reading' (duration: 138.091821ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:37:50.60466Z","caller":"traceutil/trace.go:171","msg":"trace[1036056808] linearizableReadLoop","detail":"{readStateIndex:3009; appliedIndex:3008; }","duration":"127.529521ms","start":"2026-07-21T06:37:50.477108Z","end":"2026-07-21T06:37:50.604638Z","steps":["trace[1036056808] 'read index received' (duration: 127.403321ms)","trace[1036056808] 'applied index is now lower than readState.Index' (duration: 124.83µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:37:50.604727Z","caller":"traceutil/trace.go:171","msg":"trace[1712466691] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"128.302112ms","start":"2026-07-21T06:37:50.476398Z","end":"2026-07-21T06:37:50.6047Z","steps":["trace[1712466691] 'process raft request' (duration: 128.116832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:37:50.604965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.767311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-07-21T06:37:50.60504Z","caller":"traceutil/trace.go:171","msg":"trace[749727076] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2902; }","duration":"127.948483ms","start":"2026-07-21T06:37:50.477074Z","end":"2026-07-21T06:37:50.605022Z","steps":["trace[749727076] 'agreement among raft nodes before linearized reading' (duration: 127.654251ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:37:50.614852Z","caller":"traceutil/trace.go:171","msg":"trace[2006804083] transaction","detail":"{read_only:false; response_revision:2903; number_of_response:1; }","duration":"137.34972ms","start":"2026-07-21T06:37:50.477484Z","end":"2026-07-21T06:37:50.614834Z","steps":["trace[2006804083] 'process raft request' (duration: 137.223409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:38.573305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.293595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:38:38.573416Z","caller":"traceutil/trace.go:171","msg":"trace[159659799] linearizableReadLoop","detail":"{readStateIndex:3270; appliedIndex:3269; }","duration":"259.121175ms","start":"2026-07-21T06:38:38.314278Z","end":"2026-07-21T06:38:38.573399Z","steps":["trace[159659799] 'read index received' (duration: 105.650356ms)","trace[159659799] 'applied index is now lower than readState.Index' (duration: 153.469639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:38:38.573518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.236297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:38:38.573503Z","caller":"traceutil/trace.go:171","msg":"trace[1354454749] transaction","detail":"{read_only:false; response_revision:3152; number_of_response:1; }","duration":"306.200852ms","start":"2026-07-21T06:38:38.267272Z","end":"2026-07-21T06:38:38.573473Z","steps":["trace[1354454749] 'process raft request' (duration: 152.657731ms)","trace[1354454749] 'compare' (duration: 153.146712ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:38:38.573552Z","caller":"traceutil/trace.go:171","msg":"trace[737251764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3152; }","duration":"259.295809ms","start":"2026-07-21T06:38:38.314246Z","end":"2026-07-21T06:38:38.573542Z","steps":["trace[737251764] 'agreement among raft nodes before linearized reading' (duration: 259.243478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:38.573634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:38:38.267252Z","time spent":"306.309733ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:38:38.5738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.168851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-21T06:38:38.57396Z","caller":"traceutil/trace.go:171","msg":"trace[843583242] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3152; }","duration":"238.365675ms","start":"2026-07-21T06:38:38.335575Z","end":"2026-07-21T06:38:38.57394Z","steps":["trace[843583242] 'agreement among raft nodes before linearized reading' (duration: 237.950827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:38.913744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.729051ms","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-07-21T06:38:38.91382Z","caller":"traceutil/trace.go:171","msg":"trace[791392078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3153; }","duration":"219.844342ms","start":"2026-07-21T06:38:38.693959Z","end":"2026-07-21T06:38:38.913803Z","steps":["trace[791392078] 'range keys from in-memory index tree' (duration: 219.517876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:38:38.913887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.849722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T06:38:38.914103Z","caller":"traceutil/trace.go:171","msg":"trace[1529180574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3153; }","duration":"220.013886ms","start":"2026-07-21T06:38:38.693971Z","end":"2026-07-21T06:38:38.913985Z","steps":["trace[1529180574] 'range keys from in-memory index tree' (duration: 219.677129ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:38:39.919979Z","caller":"traceutil/trace.go:171","msg":"trace[1774843604] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"137.818472ms","start":"2026-07-21T06:38:39.782134Z","end":"2026-07-21T06:38:39.919953Z","steps":["trace[1774843604] 'process raft request' (duration: 137.68215ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:02.949249Z","caller":"traceutil/trace.go:171","msg":"trace[1837598233] transaction","detail":"{read_only:false; response_revision:3276; number_of_response:1; }","duration":"125.740921ms","start":"2026-07-21T06:39:02.823484Z","end":"2026-07-21T06:39:02.949225Z","steps":["trace[1837598233] 'process raft request' (duration: 125.606427ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:04.997336Z","caller":"traceutil/trace.go:171","msg":"trace[1367376677] transaction","detail":"{read_only:false; response_revision:3285; number_of_response:1; }","duration":"116.397869ms","start":"2026-07-21T06:39:04.880919Z","end":"2026-07-21T06:39:04.997317Z","steps":["trace[1367376677] 'process raft request' (duration: 116.289656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:19.747698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.978395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:19.747833Z","caller":"traceutil/trace.go:171","msg":"trace[44126185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:3363; }","duration":"122.182323ms","start":"2026-07-21T06:39:19.625627Z","end":"2026-07-21T06:39:19.74781Z","steps":["trace[44126185] 'count revisions from in-memory index tree' (duration: 121.841152ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:21.68171Z","caller":"traceutil/trace.go:171","msg":"trace[1379947613] linearizableReadLoop","detail":"{readStateIndex:3499; appliedIndex:3498; }","duration":"100.345234ms","start":"2026-07-21T06:39:21.581336Z","end":"2026-07-21T06:39:21.681681Z","steps":["trace[1379947613] 'read index received' (duration: 99.077133ms)","trace[1379947613] 'applied index is now lower than readState.Index' (duration: 1.266411ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:21.681785Z","caller":"traceutil/trace.go:171","msg":"trace[155480372] transaction","detail":"{read_only:false; response_revision:3373; number_of_response:1; }","duration":"101.606697ms","start":"2026-07-21T06:39:21.580153Z","end":"2026-07-21T06:39:21.681759Z","steps":["trace[155480372] 'process raft request' (duration: 100.296624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:21.681861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.50719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-21T06:39:21.681916Z","caller":"traceutil/trace.go:171","msg":"trace[333901045] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3373; }","duration":"100.609383ms","start":"2026-07-21T06:39:21.581294Z","end":"2026-07-21T06:39:21.681903Z","steps":["trace[333901045] 'agreement among raft nodes before linearized reading' (duration: 100.473638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:31.310111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.756395ms","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-07-21T06:39:31.310184Z","caller":"traceutil/trace.go:171","msg":"trace[1678179328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3498; }","duration":"205.845709ms","start":"2026-07-21T06:39:31.104306Z","end":"2026-07-21T06:39:31.310151Z","steps":["trace[1678179328] 'range keys from in-memory index tree' (duration: 205.675063ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:31.538143Z","caller":"traceutil/trace.go:171","msg":"trace[858330648] transaction","detail":"{read_only:false; response_revision:3502; number_of_response:1; }","duration":"131.988917ms","start":"2026-07-21T06:39:31.406134Z","end":"2026-07-21T06:39:31.538123Z","steps":["trace[858330648] 'process raft request' (duration: 131.844012ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:33.055885Z","caller":"traceutil/trace.go:171","msg":"trace[72805746] transaction","detail":"{read_only:false; response_revision:3508; number_of_response:1; }","duration":"125.172083ms","start":"2026-07-21T06:39:32.930687Z","end":"2026-07-21T06:39:33.055859Z","steps":["trace[72805746] 'process raft request' (duration: 112.680284ms)","trace[72805746] 'compare' (duration: 12.022082ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:38.965669Z","caller":"traceutil/trace.go:171","msg":"trace[1721199193] transaction","detail":"{read_only:false; response_revision:3544; number_of_response:1; }","duration":"128.069138ms","start":"2026-07-21T06:39:38.837574Z","end":"2026-07-21T06:39:38.965643Z","steps":["trace[1721199193] 'process raft request' (duration: 127.930023ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:40.793286Z","caller":"traceutil/trace.go:171","msg":"trace[294138998] transaction","detail":"{read_only:false; response_revision:3554; number_of_response:1; }","duration":"159.580059ms","start":"2026-07-21T06:39:40.633678Z","end":"2026-07-21T06:39:40.793258Z","steps":["trace[294138998] 'process raft request' (duration: 159.119881ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:51.297309Z","caller":"traceutil/trace.go:171","msg":"trace[2037440680] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"151.889646ms","start":"2026-07-21T06:39:51.145363Z","end":"2026-07-21T06:39:51.297252Z","steps":["trace[2037440680] 'process raft request' (duration: 128.214141ms)","trace[2037440680] 'compare' (duration: 23.455397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:51.777833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.720104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:39:51.778039Z","caller":"traceutil/trace.go:171","msg":"trace[1016170111] transaction","detail":"{read_only:false; response_revision:3621; number_of_response:1; }","duration":"204.390882ms","start":"2026-07-21T06:39:51.573619Z","end":"2026-07-21T06:39:51.77801Z","steps":["trace[1016170111] 'process raft request' (duration: 39.343375ms)","trace[1016170111] 'compare' (duration: 164.546187ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:55.926807Z","caller":"traceutil/trace.go:171","msg":"trace[236778953] transaction","detail":"{read_only:false; response_revision:3644; number_of_response:1; }","duration":"111.120167ms","start":"2026-07-21T06:39:55.815655Z","end":"2026-07-21T06:39:55.926775Z","steps":["trace[236778953] 'process raft request' (duration: 76.722391ms)","trace[236778953] 'compare' (duration: 34.187876ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:55.926954Z","caller":"traceutil/trace.go:171","msg":"trace[169690432] linearizableReadLoop","detail":"{readStateIndex:3782; appliedIndex:3780; }","duration":"101.222828ms","start":"2026-07-21T06:39:55.825709Z","end":"2026-07-21T06:39:55.926932Z","steps":["trace[169690432] 'read index received' (duration: 66.603923ms)","trace[169690432] 'applied index is now lower than readState.Index' (duration: 34.617685ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:55.927073Z","caller":"traceutil/trace.go:171","msg":"trace[1159669488] transaction","detail":"{read_only:false; response_revision:3645; number_of_response:1; }","duration":"111.336715ms","start":"2026-07-21T06:39:55.815726Z","end":"2026-07-21T06:39:55.927063Z","steps":["trace[1159669488] 'process raft request' (duration: 110.993851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:55.927272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.54915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-21T06:39:55.92731Z","caller":"traceutil/trace.go:171","msg":"trace[2027021214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3645; }","duration":"101.637695ms","start":"2026-07-21T06:39:55.825663Z","end":"2026-07-21T06:39:55.927301Z","steps":["trace[2027021214] 'agreement among raft nodes before linearized reading' (duration: 101.515259ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:16.996238Z","caller":"traceutil/trace.go:171","msg":"trace[918536302] linearizableReadLoop","detail":"{readStateIndex:3900; appliedIndex:3899; }","duration":"145.51406ms","start":"2026-07-21T06:40:16.850693Z","end":"2026-07-21T06:40:16.996207Z","steps":["trace[918536302] 'read index received' (duration: 95.788283ms)","trace[918536302] 'applied index is now lower than readState.Index' (duration: 49.724547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:40:16.996377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.665577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:40:16.996401Z","caller":"traceutil/trace.go:171","msg":"trace[1473166560] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"178.931474ms","start":"2026-07-21T06:40:16.817454Z","end":"2026-07-21T06:40:16.996386Z","steps":["trace[1473166560] 'process raft request' (duration: 129.145844ms)","trace[1473166560] 'compare' (duration: 49.424923ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:16.996477Z","caller":"traceutil/trace.go:171","msg":"trace[855453744] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3759; }","duration":"145.771202ms","start":"2026-07-21T06:40:16.850638Z","end":"2026-07-21T06:40:16.996409Z","steps":["trace[855453744] 'agreement among raft nodes before linearized reading' (duration: 145.677888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:20.456452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.682242ms","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-07-21T06:41:20.456604Z","caller":"traceutil/trace.go:171","msg":"trace[1887939685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4640; }","duration":"171.84194ms","start":"2026-07-21T06:41:20.28471Z","end":"2026-07-21T06:41:20.456551Z","steps":["trace[1887939685] 'range keys from in-memory index tree' (duration: 171.526413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:20.456898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.316286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8fc9616e-32d1-479d-a785-ceaf4c300ea7\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-21T06:41:20.457009Z","caller":"traceutil/trace.go:171","msg":"trace[227429640] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8fc9616e-32d1-479d-a785-ceaf4c300ea7; range_end:; response_count:1; response_revision:4640; }","duration":"147.449012ms","start":"2026-07-21T06:41:20.309511Z","end":"2026-07-21T06:41:20.45696Z","steps":["trace[227429640] 'range keys from in-memory index tree' (duration: 147.191649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:20.457156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.178221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-07-21T06:41:20.457216Z","caller":"traceutil/trace.go:171","msg":"trace[1899419705] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4640; }","duration":"161.262055ms","start":"2026-07-21T06:41:20.295941Z","end":"2026-07-21T06:41:20.457203Z","steps":["trace[1899419705] 'range keys from in-memory index tree' (duration: 160.511646ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:20.589495Z","caller":"traceutil/trace.go:171","msg":"trace[929488046] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"124.594706ms","start":"2026-07-21T06:41:20.464883Z","end":"2026-07-21T06:41:20.589477Z","steps":["trace[929488046] 'process raft request' (duration: 124.48044ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:20.619085Z","caller":"traceutil/trace.go:171","msg":"trace[255131515] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"152.295313ms","start":"2026-07-21T06:41:20.466766Z","end":"2026-07-21T06:41:20.619061Z","steps":["trace[255131515] 'process raft request' (duration: 152.125934ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:20.619521Z","caller":"traceutil/trace.go:171","msg":"trace[181308435] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"152.647901ms","start":"2026-07-21T06:41:20.466845Z","end":"2026-07-21T06:41:20.619493Z","steps":["trace[181308435] 'process raft request' (duration: 152.175426ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:20.626423Z","caller":"traceutil/trace.go:171","msg":"trace[857933958] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"140.02078ms","start":"2026-07-21T06:41:20.486374Z","end":"2026-07-21T06:41:20.626395Z","steps":["trace[857933958] 'process raft request' (duration: 133.02782ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:20.626622Z","caller":"traceutil/trace.go:171","msg":"trace[1253950339] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"108.166581ms","start":"2026-07-21T06:41:20.518424Z","end":"2026-07-21T06:41:20.626591Z","steps":["trace[1253950339] 'process raft request' (duration: 107.892536ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:22.18586Z","caller":"traceutil/trace.go:171","msg":"trace[1218771225] linearizableReadLoop","detail":"{readStateIndex:4843; appliedIndex:4842; }","duration":"134.440288ms","start":"2026-07-21T06:41:22.051365Z","end":"2026-07-21T06:41:22.185806Z","steps":["trace[1218771225] 'read index received' (duration: 100.43481ms)","trace[1218771225] 'applied index is now lower than readState.Index' (duration: 34.004908ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:22.185879Z","caller":"traceutil/trace.go:171","msg":"trace[2040623100] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"134.569784ms","start":"2026-07-21T06:41:22.051284Z","end":"2026-07-21T06:41:22.185854Z","steps":["trace[2040623100] 'process raft request' (duration: 100.584827ms)","trace[2040623100] 'compare' (duration: 33.83273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:22.186066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.664439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-21T06:41:22.186091Z","caller":"traceutil/trace.go:171","msg":"trace[1677910048] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4666; }","duration":"134.762094ms","start":"2026-07-21T06:41:22.051322Z","end":"2026-07-21T06:41:22.186084Z","steps":["trace[1677910048] 'agreement among raft nodes before linearized reading' (duration: 134.614156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:22.186123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.120031ms","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-07-21T06:41:22.186211Z","caller":"traceutil/trace.go:171","msg":"trace[2003422804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4666; }","duration":"134.249737ms","start":"2026-07-21T06:41:22.051945Z","end":"2026-07-21T06:41:22.186195Z","steps":["trace[2003422804] 'agreement among raft nodes before linearized reading' (duration: 134.025516ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:22.355808Z","caller":"traceutil/trace.go:171","msg":"trace[1220054902] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4844; }","duration":"125.347138ms","start":"2026-07-21T06:41:22.230437Z","end":"2026-07-21T06:41:22.355784Z","steps":["trace[1220054902] 'read index received' (duration: 72.37111ms)","trace[1220054902] 'applied index is now lower than readState.Index' (duration: 52.972458ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:22.35606Z","caller":"traceutil/trace.go:171","msg":"trace[1594850328] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"155.033521ms","start":"2026-07-21T06:41:22.201012Z","end":"2026-07-21T06:41:22.356046Z","steps":["trace[1594850328] 'process raft request' (duration: 101.844463ms)","trace[1594850328] 'compare' (duration: 52.748495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:22.356449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.993261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-21T06:41:22.356496Z","caller":"traceutil/trace.go:171","msg":"trace[700119419] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4668; }","duration":"126.069165ms","start":"2026-07-21T06:41:22.230415Z","end":"2026-07-21T06:41:22.356484Z","steps":["trace[700119419] 'agreement among raft nodes before linearized reading' (duration: 125.941838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:23.064064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.583716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:41:23.064137Z","caller":"traceutil/trace.go:171","msg":"trace[1688025960] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4694; }","duration":"173.695872ms","start":"2026-07-21T06:41:22.890423Z","end":"2026-07-21T06:41:23.064119Z","steps":["trace[1688025960] 'count revisions from in-memory index tree' (duration: 173.516392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:23.064201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.238453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-07-21T06:41:23.06425Z","caller":"traceutil/trace.go:171","msg":"trace[1108876544] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4694; }","duration":"188.320628ms","start":"2026-07-21T06:41:22.875918Z","end":"2026-07-21T06:41:23.064238Z","steps":["trace[1108876544] 'range keys from in-memory index tree' (duration: 188.086735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:28.841014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.169247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52f8c844-43fe-456d-8e14-097c26831338\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-21T06:41:28.841082Z","caller":"traceutil/trace.go:171","msg":"trace[1227820499] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52f8c844-43fe-456d-8e14-097c26831338; range_end:; response_count:1; response_revision:4818; }","duration":"690.284353ms","start":"2026-07-21T06:41:28.150785Z","end":"2026-07-21T06:41:28.841069Z","steps":["trace[1227820499] 'range keys from in-memory index tree' (duration: 690.00765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:28.841117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.150764Z","time spent":"690.346597ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52f8c844-43fe-456d-8e14-097c26831338\" "} {"level":"warn","ts":"2026-07-21T06:41:28.841379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.001709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:28.841476Z","caller":"traceutil/trace.go:171","msg":"trace[748305167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4818; }","duration":"591.140706ms","start":"2026-07-21T06:41:28.250307Z","end":"2026-07-21T06:41:28.841448Z","steps":["trace[748305167] 'range keys from in-memory index tree' (duration: 590.930295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:28.841531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.250289Z","time spent":"591.22869ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:41:28.841946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.694293ms","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-07-21T06:41:28.842042Z","caller":"traceutil/trace.go:171","msg":"trace[48648114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4818; }","duration":"590.82111ms","start":"2026-07-21T06:41:28.251202Z","end":"2026-07-21T06:41:28.842023Z","steps":["trace[48648114] 'range keys from in-memory index tree' (duration: 590.561947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:28.842086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.251185Z","time spent":"590.891364ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:41:28.84258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.267486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:41:28.84268Z","caller":"traceutil/trace.go:171","msg":"trace[399838215] linearizableReadLoop","detail":"{readStateIndex:5001; appliedIndex:5000; }","duration":"210.080659ms","start":"2026-07-21T06:41:28.632582Z","end":"2026-07-21T06:41:28.842663Z","steps":["trace[399838215] 'read index received' (duration: 46.642µs)","trace[399838215] 'applied index is now lower than readState.Index' (duration: 210.032497ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:28.842695Z","caller":"traceutil/trace.go:171","msg":"trace[973243242] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"460.083034ms","start":"2026-07-21T06:41:28.382583Z","end":"2026-07-21T06:41:28.842666Z","steps":["trace[973243242] 'process raft request' (duration: 196.642398ms)","trace[973243242] 'compare' (duration: 262.035702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:28.842936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.382556Z","time spent":"460.292134ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:41:28.842957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.363744ms","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-07-21T06:41:28.84311Z","caller":"traceutil/trace.go:171","msg":"trace[1180186900] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"210.567845ms","start":"2026-07-21T06:41:28.632519Z","end":"2026-07-21T06:41:28.843087Z","steps":["trace[1180186900] 'agreement among raft nodes before linearized reading' (duration: 210.199455ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.002518Z","caller":"traceutil/trace.go:171","msg":"trace[1716471829] linearizableReadLoop","detail":"{readStateIndex:5003; appliedIndex:5002; }","duration":"159.768842ms","start":"2026-07-21T06:41:28.842725Z","end":"2026-07-21T06:41:29.002494Z","steps":["trace[1716471829] 'read index received' (duration: 10.91564ms)","trace[1716471829] 'applied index is now lower than readState.Index' (duration: 148.851912ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:29.002576Z","caller":"traceutil/trace.go:171","msg":"trace[1114077492] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"155.682328ms","start":"2026-07-21T06:41:28.846862Z","end":"2026-07-21T06:41:29.002544Z","steps":["trace[1114077492] 'process raft request' (duration: 155.575793ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.002757Z","caller":"traceutil/trace.go:171","msg":"trace[197543952] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"163.800403ms","start":"2026-07-21T06:41:28.838916Z","end":"2026-07-21T06:41:29.002716Z","steps":["trace[197543952] 'process raft request' (duration: 126.288904ms)","trace[197543952] 'compare' (duration: 36.941119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:29.002783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.073333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-21T06:41:29.002822Z","caller":"traceutil/trace.go:171","msg":"trace[118907405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4822; }","duration":"347.156047ms","start":"2026-07-21T06:41:28.655654Z","end":"2026-07-21T06:41:29.00281Z","steps":["trace[118907405] 'agreement among raft nodes before linearized reading' (duration: 346.959797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:29.002856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.655643Z","time spent":"347.20527ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T06:41:29.002862Z","caller":"traceutil/trace.go:171","msg":"trace[1168900124] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"156.041598ms","start":"2026-07-21T06:41:28.846808Z","end":"2026-07-21T06:41:29.002849Z","steps":["trace[1168900124] 'process raft request' (duration: 155.51592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:29.002845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.309322ms","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":"warn","ts":"2026-07-21T06:41:29.002845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.006988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-546b95db6b-m2275\" ","response":"range_response_count:1 size:11257"} {"level":"warn","ts":"2026-07-21T06:41:29.002863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.033037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-21T06:41:29.002916Z","caller":"traceutil/trace.go:171","msg":"trace[1036841818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4822; }","duration":"341.410427ms","start":"2026-07-21T06:41:28.661487Z","end":"2026-07-21T06:41:29.002897Z","steps":["trace[1036841818] 'agreement among raft nodes before linearized reading' (duration: 341.197626ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.002924Z","caller":"traceutil/trace.go:171","msg":"trace[911939746] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-546b95db6b-m2275; range_end:; response_count:1; response_revision:4822; }","duration":"274.23168ms","start":"2026-07-21T06:41:28.728677Z","end":"2026-07-21T06:41:29.002909Z","steps":["trace[911939746] 'agreement among raft nodes before linearized reading' (duration: 274.004828ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.002945Z","caller":"traceutil/trace.go:171","msg":"trace[2038971201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4822; }","duration":"354.128452ms","start":"2026-07-21T06:41:28.648797Z","end":"2026-07-21T06:41:29.002926Z","steps":["trace[2038971201] 'agreement among raft nodes before linearized reading' (duration: 353.866648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:29.002964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.661476Z","time spent":"341.47991ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:41:29.002989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.648738Z","time spent":"354.242697ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:41:29.002864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.82237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-21T06:41:29.003043Z","caller":"traceutil/trace.go:171","msg":"trace[991862857] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4822; }","duration":"347.054552ms","start":"2026-07-21T06:41:28.655976Z","end":"2026-07-21T06:41:29.003031Z","steps":["trace[991862857] 'agreement among raft nodes before linearized reading' (duration: 346.645941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:29.003097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:28.65596Z","time spent":"347.109194ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-21T06:41:29.037706Z","caller":"traceutil/trace.go:171","msg":"trace[250161408] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"182.962306ms","start":"2026-07-21T06:41:28.854702Z","end":"2026-07-21T06:41:29.037664Z","steps":["trace[250161408] 'process raft request' (duration: 182.787066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:29.037801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.294557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:29.037923Z","caller":"traceutil/trace.go:171","msg":"trace[916537045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4823; }","duration":"194.427875ms","start":"2026-07-21T06:41:28.843446Z","end":"2026-07-21T06:41:29.037874Z","steps":["trace[916537045] 'agreement among raft nodes before linearized reading' (duration: 194.243835ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.922367Z","caller":"traceutil/trace.go:171","msg":"trace[641084404] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"181.300053ms","start":"2026-07-21T06:41:29.741023Z","end":"2026-07-21T06:41:29.922323Z","steps":["trace[641084404] 'process raft request' (duration: 181.075851ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:29.922396Z","caller":"traceutil/trace.go:171","msg":"trace[1672028957] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"181.330714ms","start":"2026-07-21T06:41:29.741028Z","end":"2026-07-21T06:41:29.922358Z","steps":["trace[1672028957] 'process raft request' (duration: 181.198378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:31.023548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.06926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-21T06:41:31.023621Z","caller":"traceutil/trace.go:171","msg":"trace[681723049] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4846; }","duration":"178.189376ms","start":"2026-07-21T06:41:30.845408Z","end":"2026-07-21T06:41:31.023597Z","steps":["trace[681723049] 'range keys from in-memory index tree' (duration: 177.902322ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:31.023791Z","caller":"traceutil/trace.go:171","msg":"trace[1361312946] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"139.225365ms","start":"2026-07-21T06:41:30.88454Z","end":"2026-07-21T06:41:31.023766Z","steps":["trace[1361312946] 'process raft request' (duration: 77.164702ms)","trace[1361312946] 'compare' (duration: 61.862443ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:42.392028Z","caller":"traceutil/trace.go:171","msg":"trace[1292390727] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"110.321927ms","start":"2026-07-21T06:41:42.281663Z","end":"2026-07-21T06:41:42.391985Z","steps":["trace[1292390727] 'process raft request' (duration: 109.904946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:43.698832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830140053382851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:41:45.250801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000655366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-21T06:41:45.250898Z","caller":"traceutil/trace.go:171","msg":"trace[566915837] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000797934s","start":"2026-07-21T06:41:43.250082Z","end":"2026-07-21T06:41:45.25088Z","steps":["trace[566915837] 'agreement among raft nodes before linearized reading' (duration: 2.000679987s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.250965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.250067Z","time spent":"2.000879928s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:41:45.524019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97018762s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:45.524181Z","caller":"traceutil/trace.go:171","msg":"trace[147162298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5005; }","duration":"1.970347968s","start":"2026-07-21T06:41:43.553796Z","end":"2026-07-21T06:41:45.524144Z","steps":["trace[147162298] 'range keys from in-memory index tree' (duration: 1.970168029s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.525075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37562002s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:41:45.525159Z","caller":"traceutil/trace.go:171","msg":"trace[987773897] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"2.367866295s","start":"2026-07-21T06:41:43.157278Z","end":"2026-07-21T06:41:45.525144Z","steps":["trace[987773897] 'process raft request' (duration: 992.119528ms)","trace[987773897] 'compare' (duration: 1.374210115s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:45.525241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.157267Z","time spent":"2.36794965s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:41:45.713217Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.563789487s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:41:45.719492Z","caller":"traceutil/trace.go:171","msg":"trace[1933933594] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"2.559423184s","start":"2026-07-21T06:41:43.160043Z","end":"2026-07-21T06:41:45.719466Z","steps":["trace[1933933594] 'process raft request' (duration: 2.55339748s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.719616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.16003Z","time spent":"2.559516369s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:41:45.719857Z","caller":"traceutil/trace.go:171","msg":"trace[1584625711] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"2.559756441s","start":"2026-07-21T06:41:43.160068Z","end":"2026-07-21T06:41:45.719824Z","steps":["trace[1584625711] 'process raft request' (duration: 2.559358871s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.719944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.160053Z","time spent":"2.559846617s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:41:45.719943Z","caller":"traceutil/trace.go:171","msg":"trace[2079969909] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5195; }","duration":"2.522185683s","start":"2026-07-21T06:41:43.197699Z","end":"2026-07-21T06:41:45.719885Z","steps":["trace[2079969909] 'read index received' (duration: 951.708138ms)","trace[2079969909] 'applied index is now lower than readState.Index' (duration: 1.570476745s)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:45.720009Z","caller":"traceutil/trace.go:171","msg":"trace[1437991618] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"2.097793041s","start":"2026-07-21T06:41:43.622195Z","end":"2026-07-21T06:41:45.719988Z","steps":["trace[1437991618] 'process raft request' (duration: 2.097495314s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.720118Z","caller":"traceutil/trace.go:171","msg":"trace[792398410] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"1.749948989s","start":"2026-07-21T06:41:43.970159Z","end":"2026-07-21T06:41:45.720108Z","steps":["trace[792398410] 'process raft request' (duration: 1.749660603s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.720119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.622166Z","time spent":"2.097897366s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:41:45.720437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321485497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T06:41:45.720488Z","caller":"traceutil/trace.go:171","msg":"trace[295199720] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5011; }","duration":"1.321589512s","start":"2026-07-21T06:41:44.398888Z","end":"2026-07-21T06:41:45.720477Z","steps":["trace[295199720] 'agreement among raft nodes before linearized reading' (duration: 1.321383842s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.720532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:44.398869Z","time spent":"1.321654226s","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-21T06:41:45.720209Z","caller":"traceutil/trace.go:171","msg":"trace[1917408045] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"1.704847417s","start":"2026-07-21T06:41:44.01533Z","end":"2026-07-21T06:41:45.720177Z","steps":["trace[1917408045] 'process raft request' (duration: 1.704524209s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.720657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:44.01531Z","time spent":"1.705300491s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:41:45.720247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.970126Z","time spent":"1.750036583s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:41:45.720256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.522554063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T06:41:45.720898Z","caller":"traceutil/trace.go:171","msg":"trace[1070891722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5011; }","duration":"2.523232919s","start":"2026-07-21T06:41:43.197647Z","end":"2026-07-21T06:41:45.72088Z","steps":["trace[1070891722] 'agreement among raft nodes before linearized reading' (duration: 2.522473129s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.720945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.197623Z","time spent":"2.523309663s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:41:45.720295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.538479ms","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-07-21T06:41:45.721017Z","caller":"traceutil/trace.go:171","msg":"trace[1243115179] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5011; }","duration":"623.28772ms","start":"2026-07-21T06:41:45.097712Z","end":"2026-07-21T06:41:45.721Z","steps":["trace[1243115179] 'agreement among raft nodes before linearized reading' (duration: 622.483007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.72107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:45.097678Z","time spent":"623.376774ms","remote":"127.0.0.1:43630","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-07-21T06:41:45.72031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.446359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:45.721177Z","caller":"traceutil/trace.go:171","msg":"trace[1614459515] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5011; }","duration":"977.353187ms","start":"2026-07-21T06:41:44.743815Z","end":"2026-07-21T06:41:45.721168Z","steps":["trace[1614459515] 'agreement among raft nodes before linearized reading' (duration: 976.46691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.721215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:44.743759Z","time spent":"977.445202ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:41:45.720358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.408706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-21T06:41:45.721375Z","caller":"traceutil/trace.go:171","msg":"trace[1618007173] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5011; }","duration":"560.460983ms","start":"2026-07-21T06:41:45.160903Z","end":"2026-07-21T06:41:45.721364Z","steps":["trace[1618007173] 'agreement among raft nodes before linearized reading' (duration: 559.391795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.721407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:45.160886Z","time spent":"560.512555ms","remote":"127.0.0.1:43630","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":"warn","ts":"2026-07-21T06:41:45.720362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.516831977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-546b95db6b-m2275\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-07-21T06:41:45.721654Z","caller":"traceutil/trace.go:171","msg":"trace[544319597] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-546b95db6b-m2275; range_end:; response_count:1; response_revision:5011; }","duration":"2.518137427s","start":"2026-07-21T06:41:43.2035Z","end":"2026-07-21T06:41:45.721637Z","steps":["trace[544319597] 'agreement among raft nodes before linearized reading' (duration: 2.51669849s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.721715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:43.203449Z","time spent":"2.518247723s","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11871,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-546b95db6b-m2275\" "} {"level":"warn","ts":"2026-07-21T06:41:45.720386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.154554ms","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-07-21T06:41:45.721924Z","caller":"traceutil/trace.go:171","msg":"trace[1394273726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5011; }","duration":"619.721448ms","start":"2026-07-21T06:41:45.102194Z","end":"2026-07-21T06:41:45.721915Z","steps":["trace[1394273726] 'agreement among raft nodes before linearized reading' (duration: 618.128043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.721951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:45.102178Z","time spent":"619.76641ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:41:45.720395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.942876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:45.722097Z","caller":"traceutil/trace.go:171","msg":"trace[1946672273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5011; }","duration":"470.67918ms","start":"2026-07-21T06:41:45.251407Z","end":"2026-07-21T06:41:45.722086Z","steps":["trace[1946672273] 'agreement among raft nodes before linearized reading' (duration: 468.972248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.722131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:45.251383Z","time spent":"470.739673ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:41:45.720412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534592884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-21T06:41:45.722217Z","caller":"traceutil/trace.go:171","msg":"trace[1246004302] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5011; }","duration":"1.536429373s","start":"2026-07-21T06:41:44.185779Z","end":"2026-07-21T06:41:45.722208Z","steps":["trace[1246004302] 'agreement among raft nodes before linearized reading' (duration: 1.534559282s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.72225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:41:44.185761Z","time spent":"1.536481296s","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-21T06:41:45.89948Z","caller":"traceutil/trace.go:171","msg":"trace[866797533] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"173.012973ms","start":"2026-07-21T06:41:45.726442Z","end":"2026-07-21T06:41:45.899455Z","steps":["trace[866797533] 'read index received' (duration: 111.966731ms)","trace[866797533] 'applied index is now lower than readState.Index' (duration: 61.045382ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:41:45.899504Z","caller":"traceutil/trace.go:171","msg":"trace[1799891127] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"173.278447ms","start":"2026-07-21T06:41:45.726192Z","end":"2026-07-21T06:41:45.89947Z","steps":["trace[1799891127] 'process raft request' (duration: 112.226185ms)","trace[1799891127] 'compare' (duration: 60.919065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:45.8997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.241095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-52f8c844-43fe-456d-8e14-097c26831338\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-21T06:41:45.899732Z","caller":"traceutil/trace.go:171","msg":"trace[377168047] range","detail":"{range_begin:/registry/persistentvolumes/pvc-52f8c844-43fe-456d-8e14-097c26831338; range_end:; response_count:1; response_revision:5012; }","duration":"173.309799ms","start":"2026-07-21T06:41:45.726412Z","end":"2026-07-21T06:41:45.899722Z","steps":["trace[377168047] 'agreement among raft nodes before linearized reading' (duration: 173.152121ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.92419Z","caller":"traceutil/trace.go:171","msg":"trace[250291562] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"197.459445ms","start":"2026-07-21T06:41:45.726704Z","end":"2026-07-21T06:41:45.924164Z","steps":["trace[250291562] 'process raft request' (duration: 197.230453ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.924197Z","caller":"traceutil/trace.go:171","msg":"trace[1700890297] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"196.287281ms","start":"2026-07-21T06:41:45.727883Z","end":"2026-07-21T06:41:45.92417Z","steps":["trace[1700890297] 'process raft request' (duration: 196.219147ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.924431Z","caller":"traceutil/trace.go:171","msg":"trace[149999063] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"194.965271ms","start":"2026-07-21T06:41:45.729431Z","end":"2026-07-21T06:41:45.924396Z","steps":["trace[149999063] 'process raft request' (duration: 194.705826ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.924485Z","caller":"traceutil/trace.go:171","msg":"trace[1280123540] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"190.005836ms","start":"2026-07-21T06:41:45.734471Z","end":"2026-07-21T06:41:45.924476Z","steps":["trace[1280123540] 'process raft request' (duration: 189.831826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.924533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.046816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T06:41:45.924585Z","caller":"traceutil/trace.go:171","msg":"trace[587895639] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5017; }","duration":"198.113599ms","start":"2026-07-21T06:41:45.726461Z","end":"2026-07-21T06:41:45.924575Z","steps":["trace[587895639] 'agreement among raft nodes before linearized reading' (duration: 197.94362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:41:45.924699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.053232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:41:45.924726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.463527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:41:45.924733Z","caller":"traceutil/trace.go:171","msg":"trace[1058378074] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5017; }","duration":"158.125306ms","start":"2026-07-21T06:41:45.766597Z","end":"2026-07-21T06:41:45.924723Z","steps":["trace[1058378074] 'agreement among raft nodes before linearized reading' (duration: 158.075303ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.92476Z","caller":"traceutil/trace.go:171","msg":"trace[70407338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5017; }","duration":"195.52227ms","start":"2026-07-21T06:41:45.729228Z","end":"2026-07-21T06:41:45.92475Z","steps":["trace[70407338] 'agreement among raft nodes before linearized reading' (duration: 195.467807ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:45.924224Z","caller":"traceutil/trace.go:171","msg":"trace[1664440663] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"196.479091ms","start":"2026-07-21T06:41:45.727719Z","end":"2026-07-21T06:41:45.924198Z","steps":["trace[1664440663] 'process raft request' (duration: 196.339374ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:46.088839Z","caller":"traceutil/trace.go:171","msg":"trace[1505211461] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"102.748413ms","start":"2026-07-21T06:41:45.986069Z","end":"2026-07-21T06:41:46.088817Z","steps":["trace[1505211461] 'process raft request' (duration: 13.673002ms)","trace[1505211461] 'compare' (duration: 88.48603ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:26.209623Z","caller":"traceutil/trace.go:171","msg":"trace[1226282738] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"127.086192ms","start":"2026-07-21T06:42:26.082513Z","end":"2026-07-21T06:42:26.2096Z","steps":["trace[1226282738] 'process raft request' (duration: 46.073794ms)","trace[1226282738] 'compare' (duration: 80.87886ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:44.715697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-07-21T06:42:44.798079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"81.225547ms","hash":4141772109,"current-db-size-bytes":53927936,"current-db-size":"54 MB","current-db-size-in-use-bytes":29511680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-21T06:42:44.79813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4141772109,"revision":2871,"compact-revision":-1} {"level":"info","ts":"2026-07-21T06:43:44.548216Z","caller":"traceutil/trace.go:171","msg":"trace[591773893] transaction","detail":"{read_only:false; response_revision:5900; number_of_response:1; }","duration":"116.226386ms","start":"2026-07-21T06:43:44.431952Z","end":"2026-07-21T06:43:44.548178Z","steps":["trace[591773893] 'process raft request' (duration: 115.893476ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:42.474446Z","caller":"traceutil/trace.go:171","msg":"trace[1758613754] transaction","detail":"{read_only:false; response_revision:6228; number_of_response:1; }","duration":"142.461696ms","start":"2026-07-21T06:44:42.331964Z","end":"2026-07-21T06:44:42.474426Z","steps":["trace[1758613754] 'process raft request' (duration: 142.328198ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:42.474975Z","caller":"traceutil/trace.go:171","msg":"trace[751279179] linearizableReadLoop","detail":"{readStateIndex:6457; appliedIndex:6457; }","duration":"122.089366ms","start":"2026-07-21T06:44:42.35287Z","end":"2026-07-21T06:44:42.47496Z","steps":["trace[751279179] 'read index received' (duration: 122.084906ms)","trace[751279179] 'applied index is now lower than readState.Index' (duration: 3.75µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:42.475054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.16556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:42.475085Z","caller":"traceutil/trace.go:171","msg":"trace[1633318938] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:6228; }","duration":"122.252645ms","start":"2026-07-21T06:44:42.352822Z","end":"2026-07-21T06:44:42.475075Z","steps":["trace[1633318938] 'agreement among raft nodes before linearized reading' (duration: 122.182781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.341135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.270537ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T06:45:23.341247Z","caller":"traceutil/trace.go:171","msg":"trace[1994759135] linearizableReadLoop","detail":"{readStateIndex:6845; appliedIndex:6844; }","duration":"301.444586ms","start":"2026-07-21T06:45:23.039774Z","end":"2026-07-21T06:45:23.341218Z","steps":["trace[1994759135] 'read index received' (duration: 49.41µs)","trace[1994759135] 'applied index is now lower than readState.Index' (duration: 301.394016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:23.341994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.218034ms","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-07-21T06:45:23.342036Z","caller":"traceutil/trace.go:171","msg":"trace[1034081461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6602; }","duration":"302.306503ms","start":"2026-07-21T06:45:23.039718Z","end":"2026-07-21T06:45:23.342024Z","steps":["trace[1034081461] 'agreement among raft nodes before linearized reading' (duration: 302.162355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.342068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.039695Z","time spent":"302.365261ms","remote":"127.0.0.1:43630","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":"warn","ts":"2026-07-21T06:45:23.342312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.162658ms","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-07-21T06:45:23.342344Z","caller":"traceutil/trace.go:171","msg":"trace[834576784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6602; }","duration":"290.244736ms","start":"2026-07-21T06:45:23.052092Z","end":"2026-07-21T06:45:23.342336Z","steps":["trace[834576784] 'agreement among raft nodes before linearized reading' (duration: 290.157208ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:23.497875Z","caller":"traceutil/trace.go:171","msg":"trace[848541083] transaction","detail":"{read_only:false; response_revision:6603; number_of_response:1; }","duration":"151.420803ms","start":"2026-07-21T06:45:23.346437Z","end":"2026-07-21T06:45:23.497858Z","steps":["trace[848541083] 'process raft request' (duration: 151.309264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.72202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.068907ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:45:23.722188Z","caller":"traceutil/trace.go:171","msg":"trace[260860310] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6603; }","duration":"168.228194ms","start":"2026-07-21T06:45:23.553916Z","end":"2026-07-21T06:45:23.722144Z","steps":["trace[260860310] 'range keys from in-memory index tree' (duration: 168.050827ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:23.825308Z","caller":"traceutil/trace.go:171","msg":"trace[1412769472] linearizableReadLoop","detail":"{readStateIndex:6848; appliedIndex:6845; }","duration":"462.973123ms","start":"2026-07-21T06:45:23.362306Z","end":"2026-07-21T06:45:23.825279Z","steps":["trace[1412769472] 'read index received' (duration: 135.504436ms)","trace[1412769472] 'applied index is now lower than readState.Index' (duration: 327.467427ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:23.825591Z","caller":"traceutil/trace.go:171","msg":"trace[1148144766] transaction","detail":"{read_only:false; response_revision:6607; number_of_response:1; }","duration":"222.208879ms","start":"2026-07-21T06:45:23.603364Z","end":"2026-07-21T06:45:23.825573Z","steps":["trace[1148144766] 'process raft request' (duration: 222.14365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.826427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.105607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T06:45:23.826441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.032557ms","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-07-21T06:45:23.826478Z","caller":"traceutil/trace.go:171","msg":"trace[1315970145] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6607; }","duration":"445.194215ms","start":"2026-07-21T06:45:23.38127Z","end":"2026-07-21T06:45:23.826464Z","steps":["trace[1315970145] 'agreement among raft nodes before linearized reading' (duration: 445.113387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.826517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.381254Z","time spent":"445.252995ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:45:23.826519Z","caller":"traceutil/trace.go:171","msg":"trace[668443702] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6607; }","duration":"462.159296ms","start":"2026-07-21T06:45:23.36434Z","end":"2026-07-21T06:45:23.8265Z","steps":["trace[668443702] 'agreement among raft nodes before linearized reading' (duration: 461.979319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.826561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.364329Z","time spent":"462.223835ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T06:45:23.826648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.444912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"info","ts":"2026-07-21T06:45:23.826758Z","caller":"traceutil/trace.go:171","msg":"trace[1660823807] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6607; }","duration":"249.598879ms","start":"2026-07-21T06:45:23.577145Z","end":"2026-07-21T06:45:23.826744Z","steps":["trace[1660823807] 'agreement among raft nodes before linearized reading' (duration: 249.332133ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:23.825647Z","caller":"traceutil/trace.go:171","msg":"trace[1536823954] transaction","detail":"{read_only:false; response_revision:6604; number_of_response:1; }","duration":"479.115336ms","start":"2026-07-21T06:45:23.346522Z","end":"2026-07-21T06:45:23.825637Z","steps":["trace[1536823954] 'process raft request' (duration: 478.515055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.826802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.131506ms","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-07-21T06:45:23.827038Z","caller":"traceutil/trace.go:171","msg":"trace[1385034521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6607; }","duration":"460.397743ms","start":"2026-07-21T06:45:23.366626Z","end":"2026-07-21T06:45:23.827024Z","steps":["trace[1385034521] 'agreement among raft nodes before linearized reading' (duration: 459.816622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.827116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.366619Z","time spent":"460.458052ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:45:23.82692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.346504Z","time spent":"480.362027ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:45:23.825634Z","caller":"traceutil/trace.go:171","msg":"trace[2027919576] transaction","detail":"{read_only:false; response_revision:6606; number_of_response:1; }","duration":"398.861925ms","start":"2026-07-21T06:45:23.426734Z","end":"2026-07-21T06:45:23.825596Z","steps":["trace[2027919576] 'process raft request' (duration: 398.507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.827298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.426711Z","time spent":"400.527129ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:45:23.825791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.474896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-21T06:45:23.830301Z","caller":"traceutil/trace.go:171","msg":"trace[2137971481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6607; }","duration":"468.009306ms","start":"2026-07-21T06:45:23.362271Z","end":"2026-07-21T06:45:23.83028Z","steps":["trace[2137971481] 'agreement among raft nodes before linearized reading' (duration: 463.410146ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:23.825636Z","caller":"traceutil/trace.go:171","msg":"trace[324082993] transaction","detail":"{read_only:false; response_revision:6605; number_of_response:1; }","duration":"478.450236ms","start":"2026-07-21T06:45:23.347123Z","end":"2026-07-21T06:45:23.825573Z","steps":["trace[324082993] 'process raft request' (duration: 478.078841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.826921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.728982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"warn","ts":"2026-07-21T06:45:23.82694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.764501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"warn","ts":"2026-07-21T06:45:23.830585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.362259Z","time spent":"468.302882ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T06:45:23.830667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.347107Z","time spent":"483.513919ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:45:23.830728Z","caller":"traceutil/trace.go:171","msg":"trace[2138476184] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6607; }","duration":"275.564872ms","start":"2026-07-21T06:45:23.555153Z","end":"2026-07-21T06:45:23.830717Z","steps":["trace[2138476184] 'agreement among raft nodes before linearized reading' (duration: 271.688171ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:23.830845Z","caller":"traceutil/trace.go:171","msg":"trace[778226052] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6607; }","duration":"338.184813ms","start":"2026-07-21T06:45:23.492654Z","end":"2026-07-21T06:45:23.830839Z","steps":["trace[778226052] 'agreement among raft nodes before linearized reading' (duration: 333.718812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.831172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.49264Z","time spent":"338.524879ms","remote":"127.0.0.1:43600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4863,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-07-21T06:46:00.234349Z","caller":"traceutil/trace.go:171","msg":"trace[534476789] transaction","detail":"{read_only:false; response_revision:6853; number_of_response:1; }","duration":"148.730115ms","start":"2026-07-21T06:46:00.085594Z","end":"2026-07-21T06:46:00.234324Z","steps":["trace[534476789] 'process raft request' (duration: 106.507086ms)","trace[534476789] 'compare' (duration: 42.07395ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:25.844379Z","caller":"traceutil/trace.go:171","msg":"trace[227862169] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"188.419825ms","start":"2026-07-21T06:46:25.655926Z","end":"2026-07-21T06:46:25.844345Z","steps":["trace[227862169] 'process raft request' (duration: 187.713555ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:26.492142Z","caller":"traceutil/trace.go:171","msg":"trace[202555110] transaction","detail":"{read_only:false; response_revision:7009; number_of_response:1; }","duration":"135.930472ms","start":"2026-07-21T06:46:26.35618Z","end":"2026-07-21T06:46:26.492111Z","steps":["trace[202555110] 'process raft request' (duration: 135.739122ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:26.665881Z","caller":"traceutil/trace.go:171","msg":"trace[2097348438] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:1; response_revision:7009; }","duration":"100.005255ms","start":"2026-07-21T06:46:26.565842Z","end":"2026-07-21T06:46:26.665848Z","steps":["trace[2097348438] 'range keys from in-memory index tree' (duration: 99.741996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:40.820273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.497699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:46:40.820365Z","caller":"traceutil/trace.go:171","msg":"trace[1824132258] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7081; }","duration":"166.632099ms","start":"2026-07-21T06:46:40.653696Z","end":"2026-07-21T06:46:40.820328Z","steps":["trace[1824132258] 'count revisions from in-memory index tree' (duration: 166.378809ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:41.584834Z","caller":"traceutil/trace.go:171","msg":"trace[1269047776] transaction","detail":"{read_only:false; response_revision:7082; number_of_response:1; }","duration":"244.326843ms","start":"2026-07-21T06:46:41.340479Z","end":"2026-07-21T06:46:41.584805Z","steps":["trace[1269047776] 'process raft request' (duration: 244.171193ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:41.617737Z","caller":"traceutil/trace.go:171","msg":"trace[105944337] linearizableReadLoop","detail":"{readStateIndex:7345; appliedIndex:7344; }","duration":"232.76144ms","start":"2026-07-21T06:46:41.384953Z","end":"2026-07-21T06:46:41.617714Z","steps":["trace[105944337] 'read index received' (duration: 199.837332ms)","trace[105944337] 'applied index is now lower than readState.Index' (duration: 32.923238ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:41.617891Z","caller":"traceutil/trace.go:171","msg":"trace[336068453] transaction","detail":"{read_only:false; response_revision:7083; number_of_response:1; }","duration":"272.847298ms","start":"2026-07-21T06:46:41.344988Z","end":"2026-07-21T06:46:41.617836Z","steps":["trace[336068453] 'process raft request' (duration: 272.598158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:41.617995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.487231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:46:41.618044Z","caller":"traceutil/trace.go:171","msg":"trace[971725142] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:7083; }","duration":"145.584651ms","start":"2026-07-21T06:46:41.472449Z","end":"2026-07-21T06:46:41.618034Z","steps":["trace[971725142] 'agreement among raft nodes before linearized reading' (duration: 145.437521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:41.61804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.535996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-21T06:46:41.618107Z","caller":"traceutil/trace.go:171","msg":"trace[1512007105] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7083; }","duration":"157.661146ms","start":"2026-07-21T06:46:41.460428Z","end":"2026-07-21T06:46:41.61809Z","steps":["trace[1512007105] 'agreement among raft nodes before linearized reading' (duration: 157.455926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:41.618447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.487872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-21T06:46:41.618492Z","caller":"traceutil/trace.go:171","msg":"trace[1175515153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7083; }","duration":"233.564542ms","start":"2026-07-21T06:46:41.384917Z","end":"2026-07-21T06:46:41.618481Z","steps":["trace[1175515153] 'agreement among raft nodes before linearized reading' (duration: 233.431972ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:48.579008Z","caller":"traceutil/trace.go:171","msg":"trace[1243709281] transaction","detail":"{read_only:false; response_revision:7119; number_of_response:1; }","duration":"169.008764ms","start":"2026-07-21T06:46:48.409959Z","end":"2026-07-21T06:46:48.578968Z","steps":["trace[1243709281] 'process raft request' (duration: 129.572993ms)","trace[1243709281] 'compare' (duration: 39.26514ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:48.579109Z","caller":"traceutil/trace.go:171","msg":"trace[865608676] linearizableReadLoop","detail":"{readStateIndex:7384; appliedIndex:7381; }","duration":"124.29985ms","start":"2026-07-21T06:46:48.45479Z","end":"2026-07-21T06:46:48.579089Z","steps":["trace[865608676] 'read index received' (duration: 34.008938ms)","trace[865608676] 'applied index is now lower than readState.Index' (duration: 90.290122ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:48.57924Z","caller":"traceutil/trace.go:171","msg":"trace[1430877282] transaction","detail":"{read_only:false; response_revision:7120; number_of_response:1; }","duration":"168.805532ms","start":"2026-07-21T06:46:48.410425Z","end":"2026-07-21T06:46:48.579231Z","steps":["trace[1430877282] 'process raft request' (duration: 168.49857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:48.579577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.763681ms","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-07-21T06:46:48.579662Z","caller":"traceutil/trace.go:171","msg":"trace[697286141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7120; }","duration":"124.895052ms","start":"2026-07-21T06:46:48.454748Z","end":"2026-07-21T06:46:48.579643Z","steps":["trace[697286141] 'agreement among raft nodes before linearized reading' (duration: 124.608541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.030965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.270373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:49.031074Z","caller":"traceutil/trace.go:171","msg":"trace[2095300654] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:7123; }","duration":"197.453013ms","start":"2026-07-21T06:46:48.833595Z","end":"2026-07-21T06:46:49.031048Z","steps":["trace[2095300654] 'count revisions from in-memory index tree' (duration: 197.167422ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:05.805397Z","caller":"traceutil/trace.go:171","msg":"trace[829648593] transaction","detail":"{read_only:false; response_revision:7203; number_of_response:1; }","duration":"170.174204ms","start":"2026-07-21T06:47:05.635199Z","end":"2026-07-21T06:47:05.805374Z","steps":["trace[829648593] 'process raft request' (duration: 170.049864ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:05.832186Z","caller":"traceutil/trace.go:171","msg":"trace[450515712] transaction","detail":"{read_only:false; response_revision:7204; number_of_response:1; }","duration":"174.442336ms","start":"2026-07-21T06:47:05.657711Z","end":"2026-07-21T06:47:05.832154Z","steps":["trace[450515712] 'process raft request' (duration: 174.193995ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:05.832218Z","caller":"traceutil/trace.go:171","msg":"trace[443803953] transaction","detail":"{read_only:false; response_revision:7205; number_of_response:1; }","duration":"137.099786ms","start":"2026-07-21T06:47:05.695091Z","end":"2026-07-21T06:47:05.832191Z","steps":["trace[443803953] 'process raft request' (duration: 136.904944ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:35.13873Z","caller":"traceutil/trace.go:171","msg":"trace[133256625] transaction","detail":"{read_only:false; response_revision:7353; number_of_response:1; }","duration":"141.556797ms","start":"2026-07-21T06:47:34.997138Z","end":"2026-07-21T06:47:35.138694Z","steps":["trace[133256625] 'process raft request' (duration: 141.411935ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:35.311987Z","caller":"traceutil/trace.go:171","msg":"trace[1699183058] transaction","detail":"{read_only:false; response_revision:7354; number_of_response:1; }","duration":"314.771819ms","start":"2026-07-21T06:47:34.997188Z","end":"2026-07-21T06:47:35.311959Z","steps":["trace[1699183058] 'process raft request' (duration: 314.216182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:35.312136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:34.997146Z","time spent":"314.9201ms","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:44.734203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5519} {"level":"info","ts":"2026-07-21T06:47:44.809144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5519,"took":"73.405138ms","hash":3928828094,"current-db-size-bytes":53927936,"current-db-size":"54 MB","current-db-size-in-use-bytes":18661376,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-21T06:47:44.809207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3928828094,"revision":5519,"compact-revision":2871}