{"level":"warn","ts":"2026-09-19T05:59:41.939279Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T05:59:41.939353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.34:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.34:2380","--initial-cluster=instance=https://162.253.55.34:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.34:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.34:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T05:59:41.93941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T05:59:41.939419Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.34:2380"]} {"level":"info","ts":"2026-09-19T05:59:41.939434Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T05:59:41.940035Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.34:2379"]} {"level":"info","ts":"2026-09-19T05:59:41.940231Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.34:2380"],"listen-peer-urls":["https://162.253.55.34:2380"],"advertise-client-urls":["https://162.253.55.34:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.34:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.34:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T05:59:41.945384Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.941779ms"} {"level":"info","ts":"2026-09-19T05:59:41.952474Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ce80082388a7ba5","cluster-id":"32cae298a1245280"} {"level":"info","ts":"2026-09-19T05:59:41.952526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T05:59:41.952544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 became follower at term 0"} {"level":"info","ts":"2026-09-19T05:59:41.952553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ce80082388a7ba5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T05:59:41.952562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 became follower at term 1"} {"level":"info","ts":"2026-09-19T05:59:41.952594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 switched to configuration voters=(4388758396166896549)"} {"level":"warn","ts":"2026-09-19T05:59:41.967234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T05:59:41.969473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T05:59:41.971458Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T05:59:41.973471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ce80082388a7ba5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T05:59:41.984229Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T05:59:41.984396Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T05:59:41.98441Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T05:59:41.984554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ce80082388a7ba5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T05:59:41.985097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 switched to configuration voters=(4388758396166896549)"} {"level":"info","ts":"2026-09-19T05:59:41.985217Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"32cae298a1245280","local-member-id":"3ce80082388a7ba5","added-peer-id":"3ce80082388a7ba5","added-peer-peer-urls":["https://162.253.55.34:2380"]} {"level":"info","ts":"2026-09-19T05:59:41.985757Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T05:59:41.985947Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.34:2380"} {"level":"info","ts":"2026-09-19T05:59:41.986013Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.34:2380"} {"level":"info","ts":"2026-09-19T05:59:41.986043Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ce80082388a7ba5","initial-advertise-peer-urls":["https://162.253.55.34:2380"],"listen-peer-urls":["https://162.253.55.34:2380"],"advertise-client-urls":["https://162.253.55.34:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.34:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T05:59:41.986107Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T05:59:42.653095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T05:59:42.65323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T05:59:42.653336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 received MsgPreVoteResp from 3ce80082388a7ba5 at term 1"} {"level":"info","ts":"2026-09-19T05:59:42.653374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 became candidate at term 2"} {"level":"info","ts":"2026-09-19T05:59:42.653405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 received MsgVoteResp from 3ce80082388a7ba5 at term 2"} {"level":"info","ts":"2026-09-19T05:59:42.653439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ce80082388a7ba5 became leader at term 2"} {"level":"info","ts":"2026-09-19T05:59:42.653464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ce80082388a7ba5 elected leader 3ce80082388a7ba5 at term 2"} {"level":"info","ts":"2026-09-19T05:59:42.65473Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ce80082388a7ba5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.34:2379]}","request-path":"/0/members/3ce80082388a7ba5/attributes","cluster-id":"32cae298a1245280","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T05:59:42.6548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T05:59:42.655263Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T05:59:42.655331Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T05:59:42.654872Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T05:59:42.654914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T05:59:42.65651Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"32cae298a1245280","local-member-id":"3ce80082388a7ba5","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T05:59:42.656637Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T05:59:42.656685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T05:59:42.657759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T05:59:42.657864Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.34:2379"} {"level":"warn","ts":"2026-09-19T05:59:44.367479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.356014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-19T05:59:44.367581Z","caller":"traceutil/trace.go:171","msg":"trace[1905166291] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"109.567064ms","start":"2026-09-19T05:59:44.257994Z","end":"2026-09-19T05:59:44.367561Z","steps":["trace[1905166291] 'agreement among raft nodes before linearized reading' (duration: 109.348413ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.367171Z","caller":"traceutil/trace.go:171","msg":"trace[738427138] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"109.379033ms","start":"2026-09-19T05:59:44.257777Z","end":"2026-09-19T05:59:44.367156Z","steps":["trace[738427138] 'process raft request' (duration: 41.932003ms)","trace[738427138] 'compare' (duration: 66.948497ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T05:59:44.367113Z","caller":"traceutil/trace.go:171","msg":"trace[1023774512] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:23; }","duration":"108.994552ms","start":"2026-09-19T05:59:44.258089Z","end":"2026-09-19T05:59:44.367084Z","steps":["trace[1023774512] 'read index received' (duration: 41.619312ms)","trace[1023774512] 'applied index is now lower than readState.Index' (duration: 67.37399ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T05:59:44.375028Z","caller":"traceutil/trace.go:171","msg":"trace[1639233461] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"116.704346ms","start":"2026-09-19T05:59:44.258287Z","end":"2026-09-19T05:59:44.374992Z","steps":["trace[1639233461] 'process raft request' (duration: 116.563775ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375011Z","caller":"traceutil/trace.go:171","msg":"trace[1886148527] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"116.724906ms","start":"2026-09-19T05:59:44.258236Z","end":"2026-09-19T05:59:44.37496Z","steps":["trace[1886148527] 'process raft request' (duration: 116.486624ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375295Z","caller":"traceutil/trace.go:171","msg":"trace[1852202820] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"116.927507ms","start":"2026-09-19T05:59:44.258348Z","end":"2026-09-19T05:59:44.375276Z","steps":["trace[1852202820] 'process raft request' (duration: 116.578645ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375465Z","caller":"traceutil/trace.go:171","msg":"trace[473574532] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"115.696041ms","start":"2026-09-19T05:59:44.259739Z","end":"2026-09-19T05:59:44.375436Z","steps":["trace[473574532] 'process raft request' (duration: 115.308309ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375564Z","caller":"traceutil/trace.go:171","msg":"trace[1858237132] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"115.644831ms","start":"2026-09-19T05:59:44.259896Z","end":"2026-09-19T05:59:44.375541Z","steps":["trace[1858237132] 'process raft request' (duration: 115.226248ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375627Z","caller":"traceutil/trace.go:171","msg":"trace[366184329] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"116.842007ms","start":"2026-09-19T05:59:44.258768Z","end":"2026-09-19T05:59:44.37561Z","steps":["trace[366184329] 'process raft request' (duration: 116.243504ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375867Z","caller":"traceutil/trace.go:171","msg":"trace[691900271] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"113.166306ms","start":"2026-09-19T05:59:44.262685Z","end":"2026-09-19T05:59:44.375851Z","steps":["trace[691900271] 'process raft request' (duration: 112.486393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T05:59:44.375905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.622915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T05:59:44.375838Z","caller":"traceutil/trace.go:171","msg":"trace[1463154174] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"117.020707ms","start":"2026-09-19T05:59:44.258677Z","end":"2026-09-19T05:59:44.375698Z","steps":["trace[1463154174] 'process raft request' (duration: 116.292963ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:44.375952Z","caller":"traceutil/trace.go:171","msg":"trace[470788352] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"116.763707ms","start":"2026-09-19T05:59:44.259171Z","end":"2026-09-19T05:59:44.375935Z","steps":["trace[470788352] 'agreement among raft nodes before linearized reading' (duration: 116.680046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T05:59:45.80933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.401031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T05:59:45.809473Z","caller":"traceutil/trace.go:171","msg":"trace[1457648344] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"269.682929ms","start":"2026-09-19T05:59:45.539767Z","end":"2026-09-19T05:59:45.80945Z","steps":["trace[1457648344] 'process raft request' (duration: 12.572673ms)","trace[1457648344] 'compare' (duration: 256.18501ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T05:59:46.349947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.909419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T05:59:46.350246Z","caller":"traceutil/trace.go:171","msg":"trace[1314210011] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"480.569586ms","start":"2026-09-19T05:59:45.869572Z","end":"2026-09-19T05:59:46.350141Z","steps":["trace[1314210011] 'process raft request' (duration: 42.376905ms)","trace[1314210011] 'compare' (duration: 437.736237ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T05:59:46.350268Z","caller":"traceutil/trace.go:171","msg":"trace[1153167845] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"200.687018ms","start":"2026-09-19T05:59:46.149401Z","end":"2026-09-19T05:59:46.350088Z","steps":["trace[1153167845] 'read index received' (duration: 38.22µs)","trace[1153167845] 'applied index is now lower than readState.Index' (duration: 200.646788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T05:59:46.350708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T05:59:45.869552Z","time spent":"480.838927ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T05:59:46.350821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.368882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T05:59:46.351018Z","caller":"traceutil/trace.go:171","msg":"trace[858971092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:110; }","duration":"201.616594ms","start":"2026-09-19T05:59:46.149349Z","end":"2026-09-19T05:59:46.350966Z","steps":["trace[858971092] 'agreement among raft nodes before linearized reading' (duration: 200.993261ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:50.719495Z","caller":"traceutil/trace.go:171","msg":"trace[1519849554] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"158.54448ms","start":"2026-09-19T05:59:50.560915Z","end":"2026-09-19T05:59:50.71946Z","steps":["trace[1519849554] 'process raft request' (duration: 158.356709ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T05:59:50.856847Z","caller":"traceutil/trace.go:171","msg":"trace[1080569402] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"128.89235ms","start":"2026-09-19T05:59:50.727914Z","end":"2026-09-19T05:59:50.856807Z","steps":["trace[1080569402] 'process raft request' (duration: 108.449323ms)","trace[1080569402] 'compare' (duration: 20.263546ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:00:29.041533Z","caller":"traceutil/trace.go:171","msg":"trace[2099056171] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"149.443841ms","start":"2026-09-19T06:00:28.892064Z","end":"2026-09-19T06:00:29.041508Z","steps":["trace[2099056171] 'process raft request' (duration: 149.322294ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.041601Z","caller":"traceutil/trace.go:171","msg":"trace[117197758] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"134.438046ms","start":"2026-09-19T06:00:28.907131Z","end":"2026-09-19T06:00:29.041569Z","steps":["trace[117197758] 'process raft request' (duration: 134.304447ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.041639Z","caller":"traceutil/trace.go:171","msg":"trace[160013650] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"134.410934ms","start":"2026-09-19T06:00:28.907216Z","end":"2026-09-19T06:00:29.041627Z","steps":["trace[160013650] 'process raft request' (duration: 134.254555ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.04169Z","caller":"traceutil/trace.go:171","msg":"trace[2039622033] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"131.362366ms","start":"2026-09-19T06:00:28.910313Z","end":"2026-09-19T06:00:29.041675Z","steps":["trace[2039622033] 'process raft request' (duration: 131.271891ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.041526Z","caller":"traceutil/trace.go:171","msg":"trace[754321691] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"149.851066ms","start":"2026-09-19T06:00:28.891625Z","end":"2026-09-19T06:00:29.041476Z","steps":["trace[754321691] 'process raft request' (duration: 138.857457ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.041704Z","caller":"traceutil/trace.go:171","msg":"trace[946459893] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"130.76044ms","start":"2026-09-19T06:00:28.910912Z","end":"2026-09-19T06:00:29.041673Z","steps":["trace[946459893] 'process raft request' (duration: 130.718247ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.04201Z","caller":"traceutil/trace.go:171","msg":"trace[552904730] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"134.643838ms","start":"2026-09-19T06:00:28.907303Z","end":"2026-09-19T06:00:29.041947Z","steps":["trace[552904730] 'process raft request' (duration: 134.230383ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.046799Z","caller":"traceutil/trace.go:171","msg":"trace[1263832724] transaction","detail":"{read_only:false; number_of_response:1; response_revision:515; }","duration":"118.115117ms","start":"2026-09-19T06:00:28.928655Z","end":"2026-09-19T06:00:29.04677Z","steps":["trace[1263832724] 'process raft request' (duration: 117.581504ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.04716Z","caller":"traceutil/trace.go:171","msg":"trace[822968582] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"114.338584ms","start":"2026-09-19T06:00:28.932805Z","end":"2026-09-19T06:00:29.047144Z","steps":["trace[822968582] 'process raft request' (duration: 113.78643ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.047215Z","caller":"traceutil/trace.go:171","msg":"trace[1656478545] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"123.86852ms","start":"2026-09-19T06:00:28.923331Z","end":"2026-09-19T06:00:29.0472Z","steps":["trace[1656478545] 'process raft request' (duration: 122.267512ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.047302Z","caller":"traceutil/trace.go:171","msg":"trace[2023902369] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"118.728075ms","start":"2026-09-19T06:00:28.928563Z","end":"2026-09-19T06:00:29.047291Z","steps":["trace[2023902369] 'process raft request' (duration: 117.547502ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:29.047385Z","caller":"traceutil/trace.go:171","msg":"trace[145085540] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"123.691639ms","start":"2026-09-19T06:00:28.923685Z","end":"2026-09-19T06:00:29.047377Z","steps":["trace[145085540] 'process raft request' (duration: 122.173476ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:35.688213Z","caller":"traceutil/trace.go:171","msg":"trace[629579198] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"373.470225ms","start":"2026-09-19T06:00:35.314684Z","end":"2026-09-19T06:00:35.688154Z","steps":["trace[629579198] 'process raft request' (duration: 372.876272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:35.688762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:00:35.314647Z","time spent":"373.670256ms","remote":"127.0.0.1:38906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:00:36.339186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.087806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:00:36.339457Z","caller":"traceutil/trace.go:171","msg":"trace[398389779] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"492.136599ms","start":"2026-09-19T06:00:35.847295Z","end":"2026-09-19T06:00:36.339432Z","steps":["trace[398389779] 'read index received' (duration: 438.424751ms)","trace[398389779] 'applied index is now lower than readState.Index' (duration: 53.710548ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:00:36.339566Z","caller":"traceutil/trace.go:171","msg":"trace[339948970] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"594.402903ms","start":"2026-09-19T06:00:35.745146Z","end":"2026-09-19T06:00:36.339549Z","steps":["trace[339948970] 'process raft request' (duration: 56.796438ms)","trace[339948970] 'compare' (duration: 536.883625ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:00:36.339655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:00:35.745112Z","time spent":"594.490588ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:00:36.340088Z","caller":"traceutil/trace.go:171","msg":"trace[1068287835] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"570.736803ms","start":"2026-09-19T06:00:35.769336Z","end":"2026-09-19T06:00:36.340073Z","steps":["trace[1068287835] 'process raft request' (duration: 570.021683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:36.340232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:00:35.769304Z","time spent":"570.86252ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:00:36.340263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.464368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T06:00:36.340214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.89297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xc6bp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-19T06:00:36.340365Z","caller":"traceutil/trace.go:171","msg":"trace[1731341530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"368.615207ms","start":"2026-09-19T06:00:35.971724Z","end":"2026-09-19T06:00:36.340339Z","steps":["trace[1731341530] 'agreement among raft nodes before linearized reading' (duration: 368.242227ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:36.340394Z","caller":"traceutil/trace.go:171","msg":"trace[33540888] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xc6bp; range_end:; response_count:1; response_revision:615; }","duration":"493.155495ms","start":"2026-09-19T06:00:35.847211Z","end":"2026-09-19T06:00:36.340367Z","steps":["trace[33540888] 'agreement among raft nodes before linearized reading' (duration: 492.758323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:36.340438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:00:35.971704Z","time spent":"368.725243ms","remote":"127.0.0.1:39088","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":"warn","ts":"2026-09-19T06:00:36.340452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:00:35.847144Z","time spent":"493.293823ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-xc6bp\" "} {"level":"warn","ts":"2026-09-19T06:00:36.340588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.20914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:36.340694Z","caller":"traceutil/trace.go:171","msg":"trace[1876446304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"206.614643ms","start":"2026-09-19T06:00:36.13406Z","end":"2026-09-19T06:00:36.340675Z","steps":["trace[1876446304] 'agreement among raft nodes before linearized reading' (duration: 206.091964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:41.299365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.805294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:41.299797Z","caller":"traceutil/trace.go:171","msg":"trace[217827103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"163.157232ms","start":"2026-09-19T06:00:41.136387Z","end":"2026-09-19T06:00:41.299545Z","steps":["trace[217827103] 'range keys from in-memory index tree' (duration: 162.694917ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:41.326599Z","caller":"traceutil/trace.go:171","msg":"trace[37781134] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"187.643957ms","start":"2026-09-19T06:00:41.138895Z","end":"2026-09-19T06:00:41.326539Z","steps":["trace[37781134] 'process raft request' (duration: 187.362782ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.597533Z","caller":"traceutil/trace.go:171","msg":"trace[612172453] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"182.45132ms","start":"2026-09-19T06:00:53.415054Z","end":"2026-09-19T06:00:53.597505Z","steps":["trace[612172453] 'process raft request' (duration: 182.078493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.597777Z","caller":"traceutil/trace.go:171","msg":"trace[1166257710] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"175.386853ms","start":"2026-09-19T06:00:53.422075Z","end":"2026-09-19T06:00:53.597462Z","steps":["trace[1166257710] 'read index received' (duration: 175.122131ms)","trace[1166257710] 'applied index is now lower than readState.Index' (duration: 263.472µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:00:53.597966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.826364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:00:53.598422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.86424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:00:53.598486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.55669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:00:53.598474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.389629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:53.598504Z","caller":"traceutil/trace.go:171","msg":"trace[1890890329] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:784; }","duration":"158.977806ms","start":"2026-09-19T06:00:53.439511Z","end":"2026-09-19T06:00:53.598489Z","steps":["trace[1890890329] 'agreement among raft nodes before linearized reading' (duration: 158.826078ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.598537Z","caller":"traceutil/trace.go:171","msg":"trace[1680750240] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:784; }","duration":"144.606512ms","start":"2026-09-19T06:00:53.453914Z","end":"2026-09-19T06:00:53.598521Z","steps":["trace[1680750240] 'agreement among raft nodes before linearized reading' (duration: 144.517048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:53.598518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.090779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:53.59857Z","caller":"traceutil/trace.go:171","msg":"trace[2004992201] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:784; }","duration":"174.489163ms","start":"2026-09-19T06:00:53.424047Z","end":"2026-09-19T06:00:53.598536Z","steps":["trace[2004992201] 'agreement among raft nodes before linearized reading' (duration: 174.360216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:53.59842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.087045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:00:53.598422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.888741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:53.598777Z","caller":"traceutil/trace.go:171","msg":"trace[251507354] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:784; }","duration":"159.26709ms","start":"2026-09-19T06:00:53.439498Z","end":"2026-09-19T06:00:53.598765Z","steps":["trace[251507354] 'agreement among raft nodes before linearized reading' (duration: 158.843139ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.598743Z","caller":"traceutil/trace.go:171","msg":"trace[1156880745] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:784; }","duration":"176.471794ms","start":"2026-09-19T06:00:53.422243Z","end":"2026-09-19T06:00:53.598715Z","steps":["trace[1156880745] 'agreement among raft nodes before linearized reading' (duration: 176.018843ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.598886Z","caller":"traceutil/trace.go:171","msg":"trace[117403702] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:784; }","duration":"144.177472ms","start":"2026-09-19T06:00:53.454403Z","end":"2026-09-19T06:00:53.598581Z","steps":["trace[117403702] 'agreement among raft nodes before linearized reading' (duration: 144.085478ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:00:53.598309Z","caller":"traceutil/trace.go:171","msg":"trace[234705457] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:784; }","duration":"176.095006ms","start":"2026-09-19T06:00:53.422058Z","end":"2026-09-19T06:00:53.598153Z","steps":["trace[234705457] 'agreement among raft nodes before linearized reading' (duration: 175.807042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:53.598865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.888755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:53.599068Z","caller":"traceutil/trace.go:171","msg":"trace[1118173990] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:784; }","duration":"145.191979ms","start":"2026-09-19T06:00:53.453856Z","end":"2026-09-19T06:00:53.599048Z","steps":["trace[1118173990] 'agreement among raft nodes before linearized reading' (duration: 144.840503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:00:53.599172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.84908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:00:53.599251Z","caller":"traceutil/trace.go:171","msg":"trace[1987609510] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:784; }","duration":"158.952044ms","start":"2026-09-19T06:00:53.440286Z","end":"2026-09-19T06:00:53.599239Z","steps":["trace[1987609510] 'agreement among raft nodes before linearized reading' (duration: 158.0039ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:01:17.011185Z","caller":"traceutil/trace.go:171","msg":"trace[777664111] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"101.415539ms","start":"2026-09-19T06:01:16.90972Z","end":"2026-09-19T06:01:17.011135Z","steps":["trace[777664111] 'process raft request' (duration: 101.187451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:01:17.038717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.539488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:01:17.038789Z","caller":"traceutil/trace.go:171","msg":"trace[2079529420] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1148; }","duration":"100.626701ms","start":"2026-09-19T06:01:16.938144Z","end":"2026-09-19T06:01:17.038771Z","steps":["trace[2079529420] 'agreement among raft nodes before linearized reading' (duration: 100.532108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:01:17.038715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.500796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:01:17.039211Z","caller":"traceutil/trace.go:171","msg":"trace[1748013462] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1148; }","duration":"101.010484ms","start":"2026-09-19T06:01:16.938188Z","end":"2026-09-19T06:01:17.039198Z","steps":["trace[1748013462] 'agreement among raft nodes before linearized reading' (duration: 100.485926ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:12.708381Z","caller":"traceutil/trace.go:171","msg":"trace[94155848] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"366.616855ms","start":"2026-09-19T06:02:12.341738Z","end":"2026-09-19T06:02:12.708355Z","steps":["trace[94155848] 'process raft request' (duration: 366.447661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:02:12.708535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:02:12.34171Z","time spent":"366.749178ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:02:46.462322Z","caller":"traceutil/trace.go:171","msg":"trace[1681027855] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2176; }","duration":"130.627008ms","start":"2026-09-19T06:02:46.331664Z","end":"2026-09-19T06:02:46.462291Z","steps":["trace[1681027855] 'read index received' (duration: 130.380613ms)","trace[1681027855] 'applied index is now lower than readState.Index' (duration: 245.095µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:02:46.462716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.019255ms","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-09-19T06:02:46.462875Z","caller":"traceutil/trace.go:171","msg":"trace[870954446] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2095; }","duration":"131.190969ms","start":"2026-09-19T06:02:46.33166Z","end":"2026-09-19T06:02:46.462851Z","steps":["trace[870954446] 'agreement among raft nodes before linearized reading' (duration: 130.849542ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:46.46326Z","caller":"traceutil/trace.go:171","msg":"trace[1030608934] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"132.396282ms","start":"2026-09-19T06:02:46.330845Z","end":"2026-09-19T06:02:46.463241Z","steps":["trace[1030608934] 'process raft request' (duration: 131.286751ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:47.269695Z","caller":"traceutil/trace.go:171","msg":"trace[606483135] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"206.523602ms","start":"2026-09-19T06:02:47.063148Z","end":"2026-09-19T06:02:47.269672Z","steps":["trace[606483135] 'read index received' (duration: 206.39924ms)","trace[606483135] 'applied index is now lower than readState.Index' (duration: 123.172µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:02:47.269963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.751656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.34\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-19T06:02:47.269983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.230157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T06:02:47.270029Z","caller":"traceutil/trace.go:171","msg":"trace[2140511360] range","detail":"{range_begin:/registry/masterleases/162.253.55.34; range_end:; response_count:1; response_revision:2099; }","duration":"206.91178ms","start":"2026-09-19T06:02:47.063107Z","end":"2026-09-19T06:02:47.270019Z","steps":["trace[2140511360] 'agreement among raft nodes before linearized reading' (duration: 206.680705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:02:47.270017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.005273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:02:47.270043Z","caller":"traceutil/trace.go:171","msg":"trace[952285332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2099; }","duration":"134.345829ms","start":"2026-09-19T06:02:47.135683Z","end":"2026-09-19T06:02:47.270029Z","steps":["trace[952285332] 'agreement among raft nodes before linearized reading' (duration: 134.155645ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:47.270094Z","caller":"traceutil/trace.go:171","msg":"trace[1417683072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2099; }","duration":"134.111015ms","start":"2026-09-19T06:02:47.135966Z","end":"2026-09-19T06:02:47.270077Z","steps":["trace[1417683072] 'agreement among raft nodes before linearized reading' (duration: 133.909521ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:47.269756Z","caller":"traceutil/trace.go:171","msg":"trace[199908339] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"233.204893ms","start":"2026-09-19T06:02:47.03652Z","end":"2026-09-19T06:02:47.269725Z","steps":["trace[199908339] 'process raft request' (duration: 233.000029ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:47.438266Z","caller":"traceutil/trace.go:171","msg":"trace[1869610342] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"162.679743ms","start":"2026-09-19T06:02:47.275556Z","end":"2026-09-19T06:02:47.438236Z","steps":["trace[1869610342] 'process raft request' (duration: 162.538541ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:02:47.610707Z","caller":"traceutil/trace.go:171","msg":"trace[882917464] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"122.375906ms","start":"2026-09-19T06:02:47.488303Z","end":"2026-09-19T06:02:47.610678Z","steps":["trace[882917464] 'process raft request' (duration: 121.58614ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:45.954531Z","caller":"traceutil/trace.go:171","msg":"trace[1092466610] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"104.569153ms","start":"2026-09-19T06:03:45.849938Z","end":"2026-09-19T06:03:45.954507Z","steps":["trace[1092466610] 'process raft request' (duration: 11.890214ms)","trace[1092466610] 'compare' (duration: 91.714444ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:48.305157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.504776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:48.305233Z","caller":"traceutil/trace.go:171","msg":"trace[234779435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2482; }","duration":"168.618347ms","start":"2026-09-19T06:03:48.136601Z","end":"2026-09-19T06:03:48.305219Z","steps":["trace[234779435] 'range keys from in-memory index tree' (duration: 168.437884ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:48.608655Z","caller":"traceutil/trace.go:171","msg":"trace[627613379] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"150.743006ms","start":"2026-09-19T06:03:48.457887Z","end":"2026-09-19T06:03:48.608631Z","steps":["trace[627613379] 'process raft request' (duration: 150.623304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:48.962793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.62733ms","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-09-19T06:03:48.962886Z","caller":"traceutil/trace.go:171","msg":"trace[1137518079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2483; }","duration":"189.774772ms","start":"2026-09-19T06:03:48.77309Z","end":"2026-09-19T06:03:48.962864Z","steps":["trace[1137518079] 'range keys from in-memory index tree' (duration: 189.379466ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:50.132223Z","caller":"traceutil/trace.go:171","msg":"trace[1058677498] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"118.982481ms","start":"2026-09-19T06:03:50.01322Z","end":"2026-09-19T06:03:50.132202Z","steps":["trace[1058677498] 'process raft request' (duration: 96.77827ms)","trace[1058677498] 'compare' (duration: 21.814215ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:50.138278Z","caller":"traceutil/trace.go:171","msg":"trace[607209431] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"124.301957ms","start":"2026-09-19T06:03:50.013944Z","end":"2026-09-19T06:03:50.138246Z","steps":["trace[607209431] 'process raft request' (duration: 124.070623ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:50.822073Z","caller":"traceutil/trace.go:171","msg":"trace[1262197318] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"142.098096ms","start":"2026-09-19T06:03:50.679953Z","end":"2026-09-19T06:03:50.822051Z","steps":["trace[1262197318] 'process raft request' (duration: 141.922453ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:51.114695Z","caller":"traceutil/trace.go:171","msg":"trace[561139015] linearizableReadLoop","detail":"{readStateIndex:2592; appliedIndex:2591; }","duration":"107.614483ms","start":"2026-09-19T06:03:51.007058Z","end":"2026-09-19T06:03:51.114672Z","steps":["trace[561139015] 'read index received' (duration: 107.45538ms)","trace[561139015] 'applied index is now lower than readState.Index' (duration: 158.083µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:51.114738Z","caller":"traceutil/trace.go:171","msg":"trace[2142843383] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"119.73886ms","start":"2026-09-19T06:03:50.994965Z","end":"2026-09-19T06:03:51.114704Z","steps":["trace[2142843383] 'process raft request' (duration: 119.571658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:51.115191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.348729ms","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-09-19T06:03:51.115267Z","caller":"traceutil/trace.go:171","msg":"trace[2099293963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2495; }","duration":"107.500011ms","start":"2026-09-19T06:03:51.007748Z","end":"2026-09-19T06:03:51.115248Z","steps":["trace[2099293963] 'agreement among raft nodes before linearized reading' (duration: 107.072764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:51.115562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.478157ms","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-09-19T06:03:51.11561Z","caller":"traceutil/trace.go:171","msg":"trace[725123995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2495; }","duration":"108.588948ms","start":"2026-09-19T06:03:51.007009Z","end":"2026-09-19T06:03:51.115598Z","steps":["trace[725123995] 'agreement among raft nodes before linearized reading' (duration: 107.783706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:51.428199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.676541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:51.428365Z","caller":"traceutil/trace.go:171","msg":"trace[1817192557] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"307.633194ms","start":"2026-09-19T06:03:51.120678Z","end":"2026-09-19T06:03:51.428311Z","steps":["trace[1817192557] 'process raft request' (duration: 77.689259ms)","trace[1817192557] 'compare' (duration: 229.533929ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:51.428412Z","caller":"traceutil/trace.go:171","msg":"trace[270328706] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"127.454285ms","start":"2026-09-19T06:03:51.30094Z","end":"2026-09-19T06:03:51.428394Z","steps":["trace[270328706] 'process raft request' (duration: 127.375314ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:51.428441Z","caller":"traceutil/trace.go:171","msg":"trace[1247893346] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2592; }","duration":"291.191698ms","start":"2026-09-19T06:03:51.137219Z","end":"2026-09-19T06:03:51.428411Z","steps":["trace[1247893346] 'read index received' (duration: 20.747936ms)","trace[1247893346] 'applied index is now lower than readState.Index' (duration: 270.441412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:51.428512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:51.120658Z","time spent":"307.762166ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:03:51.428595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.35365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:51.428648Z","caller":"traceutil/trace.go:171","msg":"trace[906611349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2498; }","duration":"291.444491ms","start":"2026-09-19T06:03:51.137191Z","end":"2026-09-19T06:03:51.428635Z","steps":["trace[906611349] 'agreement among raft nodes before linearized reading' (duration: 291.337699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:51.428675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.919914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-09-19T06:03:51.428653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.260316ms","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-09-19T06:03:51.42871Z","caller":"traceutil/trace.go:171","msg":"trace[706048361] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2498; }","duration":"240.976694ms","start":"2026-09-19T06:03:51.18772Z","end":"2026-09-19T06:03:51.428696Z","steps":["trace[706048361] 'agreement among raft nodes before linearized reading' (duration: 240.833512ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:51.428776Z","caller":"traceutil/trace.go:171","msg":"trace[1093322015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2498; }","duration":"168.418148ms","start":"2026-09-19T06:03:51.26034Z","end":"2026-09-19T06:03:51.428758Z","steps":["trace[1093322015] 'agreement among raft nodes before linearized reading' (duration: 168.254956ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:51.903681Z","caller":"traceutil/trace.go:171","msg":"trace[1966403360] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"225.116968ms","start":"2026-09-19T06:03:51.678537Z","end":"2026-09-19T06:03:51.903654Z","steps":["trace[1966403360] 'process raft request' (duration: 224.937055ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:52.315871Z","caller":"traceutil/trace.go:171","msg":"trace[2130922406] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"177.664963ms","start":"2026-09-19T06:03:52.138178Z","end":"2026-09-19T06:03:52.315843Z","steps":["trace[2130922406] 'process raft request' (duration: 177.495511ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:52.337459Z","caller":"traceutil/trace.go:171","msg":"trace[963136374] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"194.235691ms","start":"2026-09-19T06:03:52.143199Z","end":"2026-09-19T06:03:52.337435Z","steps":["trace[963136374] 'process raft request' (duration: 194.096829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:52.550268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.843112ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T06:03:52.550425Z","caller":"traceutil/trace.go:171","msg":"trace[125431479] linearizableReadLoop","detail":"{readStateIndex:2602; appliedIndex:2600; }","duration":"392.019168ms","start":"2026-09-19T06:03:52.158387Z","end":"2026-09-19T06:03:52.550407Z","steps":["trace[125431479] 'read index received' (duration: 157.239653ms)","trace[125431479] 'applied index is now lower than readState.Index' (duration: 234.777675ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:52.550671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.269213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-19T06:03:52.55073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.357281ms","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-09-19T06:03:52.550755Z","caller":"traceutil/trace.go:171","msg":"trace[268884819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2504; }","duration":"392.398625ms","start":"2026-09-19T06:03:52.158345Z","end":"2026-09-19T06:03:52.550743Z","steps":["trace[268884819] 'agreement among raft nodes before linearized reading' (duration: 392.1727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:52.550855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:52.158332Z","time spent":"392.511257ms","remote":"127.0.0.1:39088","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":"info","ts":"2026-09-19T06:03:52.550796Z","caller":"traceutil/trace.go:171","msg":"trace[1140042190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2504; }","duration":"380.420182ms","start":"2026-09-19T06:03:52.170355Z","end":"2026-09-19T06:03:52.550775Z","steps":["trace[1140042190] 'agreement among raft nodes before linearized reading' (duration: 380.2703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:52.550999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:52.170311Z","time spent":"380.667915ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T06:03:53.412176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.478694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:53.412605Z","caller":"traceutil/trace.go:171","msg":"trace[1209168092] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"245.764027ms","start":"2026-09-19T06:03:53.166811Z","end":"2026-09-19T06:03:53.412575Z","steps":["trace[1209168092] 'process raft request' (duration: 72.701853ms)","trace[1209168092] 'compare' (duration: 172.120489ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:53.86998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.637607ms","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-09-19T06:03:53.870114Z","caller":"traceutil/trace.go:171","msg":"trace[350690248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2511; }","duration":"138.80553ms","start":"2026-09-19T06:03:53.73129Z","end":"2026-09-19T06:03:53.870096Z","steps":["trace[350690248] 'range keys from in-memory index tree' (duration: 138.620077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:54.37258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.606202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-19T06:03:54.372664Z","caller":"traceutil/trace.go:171","msg":"trace[899910838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2512; }","duration":"182.727624ms","start":"2026-09-19T06:03:54.189914Z","end":"2026-09-19T06:03:54.372642Z","steps":["trace[899910838] 'range keys from in-memory index tree' (duration: 182.411239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:54.373716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.621122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:54.373784Z","caller":"traceutil/trace.go:171","msg":"trace[1090568138] range","detail":"{range_begin:/registry/secrets/auth-system/; range_end:/registry/secrets/auth-system0; response_count:0; response_revision:2512; }","duration":"182.710534ms","start":"2026-09-19T06:03:54.191058Z","end":"2026-09-19T06:03:54.373768Z","steps":["trace[1090568138] 'range keys from in-memory index tree' (duration: 181.180579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:54.684061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.459295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:54.684363Z","caller":"traceutil/trace.go:171","msg":"trace[354476271] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"204.597256ms","start":"2026-09-19T06:03:54.479742Z","end":"2026-09-19T06:03:54.684339Z","steps":["trace[354476271] 'process raft request' (duration: 204.509945ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:54.68436Z","caller":"traceutil/trace.go:171","msg":"trace[518395352] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"340.681389ms","start":"2026-09-19T06:03:54.343651Z","end":"2026-09-19T06:03:54.684333Z","steps":["trace[518395352] 'process raft request' (duration: 130.849168ms)","trace[518395352] 'compare' (duration: 209.319413ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:54.684501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:54.343607Z","time spent":"340.81698ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:03:54.684569Z","caller":"traceutil/trace.go:171","msg":"trace[2083684536] linearizableReadLoop","detail":"{readStateIndex:2613; appliedIndex:2611; }","duration":"338.338111ms","start":"2026-09-19T06:03:54.346192Z","end":"2026-09-19T06:03:54.68453Z","steps":["trace[2083684536] 'read index received' (duration: 9.099007ms)","trace[2083684536] 'applied index is now lower than readState.Index' (duration: 329.235154ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:54.684762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.547795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:54.684823Z","caller":"traceutil/trace.go:171","msg":"trace[2061543297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2515; }","duration":"338.655286ms","start":"2026-09-19T06:03:54.34615Z","end":"2026-09-19T06:03:54.684805Z","steps":["trace[2061543297] 'agreement among raft nodes before linearized reading' (duration: 338.521364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:54.684846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.89272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:03:54.684865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.637153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-09-19T06:03:54.684872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:54.346137Z","time spent":"338.723197ms","remote":"127.0.0.1:38814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T06:03:54.684885Z","caller":"traceutil/trace.go:171","msg":"trace[1561056452] range","detail":"{range_begin:/registry/secrets/auth-system/; range_end:/registry/secrets/auth-system0; response_count:0; response_revision:2515; }","duration":"158.93198ms","start":"2026-09-19T06:03:54.525941Z","end":"2026-09-19T06:03:54.684873Z","steps":["trace[1561056452] 'agreement among raft nodes before linearized reading' (duration: 158.831949ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:54.68491Z","caller":"traceutil/trace.go:171","msg":"trace[1485721857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2515; }","duration":"308.726984ms","start":"2026-09-19T06:03:54.376169Z","end":"2026-09-19T06:03:54.684896Z","steps":["trace[1485721857] 'agreement among raft nodes before linearized reading' (duration: 308.541891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:54.684943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:54.37609Z","time spent":"308.844556ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T06:03:55.118697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.035332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:55.118811Z","caller":"traceutil/trace.go:171","msg":"trace[1319426323] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2616; }","duration":"347.597417ms","start":"2026-09-19T06:03:54.771194Z","end":"2026-09-19T06:03:55.118791Z","steps":["trace[1319426323] 'read index received' (duration: 32.344022ms)","trace[1319426323] 'applied index is now lower than readState.Index' (duration: 315.251575ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:55.119086Z","caller":"traceutil/trace.go:171","msg":"trace[493535001] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"403.042169ms","start":"2026-09-19T06:03:54.716024Z","end":"2026-09-19T06:03:55.119066Z","steps":["trace[493535001] 'process raft request' (duration: 87.53382ms)","trace[493535001] 'compare' (duration: 314.584885ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:55.119219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:54.715999Z","time spent":"403.166991ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:03:55.119403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:55.119467Z","caller":"traceutil/trace.go:171","msg":"trace[1923264369] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:0; response_revision:2518; }","duration":"158.358048ms","start":"2026-09-19T06:03:54.961092Z","end":"2026-09-19T06:03:55.11945Z","steps":["trace[1923264369] 'agreement among raft nodes before linearized reading' (duration: 158.199245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:55.119556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.38804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:55.119596Z","caller":"traceutil/trace.go:171","msg":"trace[1758746293] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2518; }","duration":"348.43123ms","start":"2026-09-19T06:03:54.771153Z","end":"2026-09-19T06:03:55.119584Z","steps":["trace[1758746293] 'agreement among raft nodes before linearized reading' (duration: 348.362049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:55.119628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:54.77104Z","time spent":"348.578203ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-19T06:03:55.863892Z","caller":"traceutil/trace.go:171","msg":"trace[1508649741] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"160.389369ms","start":"2026-09-19T06:03:55.703464Z","end":"2026-09-19T06:03:55.863853Z","steps":["trace[1508649741] 'process raft request' (duration: 71.125603ms)","trace[1508649741] 'compare' (duration: 89.134614ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:55.904976Z","caller":"traceutil/trace.go:171","msg":"trace[1099214598] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"153.551979ms","start":"2026-09-19T06:03:55.751399Z","end":"2026-09-19T06:03:55.904951Z","steps":["trace[1099214598] 'process raft request' (duration: 153.478518ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:55.904992Z","caller":"traceutil/trace.go:171","msg":"trace[1638695736] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"201.462191ms","start":"2026-09-19T06:03:55.703507Z","end":"2026-09-19T06:03:55.904969Z","steps":["trace[1638695736] 'process raft request' (duration: 201.218117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.175545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.468442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:56.175635Z","caller":"traceutil/trace.go:171","msg":"trace[1442962210] linearizableReadLoop","detail":"{readStateIndex:2625; appliedIndex:2624; }","duration":"196.179421ms","start":"2026-09-19T06:03:55.979439Z","end":"2026-09-19T06:03:56.175618Z","steps":["trace[1442962210] 'read index received' (duration: 54.530207ms)","trace[1442962210] 'applied index is now lower than readState.Index' (duration: 141.644114ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:56.175718Z","caller":"traceutil/trace.go:171","msg":"trace[852615493] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"262.075311ms","start":"2026-09-19T06:03:55.913633Z","end":"2026-09-19T06:03:56.175708Z","steps":["trace[852615493] 'process raft request' (duration: 120.386765ms)","trace[852615493] 'compare' (duration: 141.36415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:56.175964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.501446ms","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-09-19T06:03:56.176054Z","caller":"traceutil/trace.go:171","msg":"trace[1628623178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2526; }","duration":"196.631378ms","start":"2026-09-19T06:03:55.979401Z","end":"2026-09-19T06:03:56.176032Z","steps":["trace[1628623178] 'agreement among raft nodes before linearized reading' (duration: 196.330853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.515253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.616888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:56.515596Z","caller":"traceutil/trace.go:171","msg":"trace[1144839973] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"137.874044ms","start":"2026-09-19T06:03:56.377703Z","end":"2026-09-19T06:03:56.515577Z","steps":["trace[1144839973] 'process raft request' (duration: 137.825873ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:56.51582Z","caller":"traceutil/trace.go:171","msg":"trace[1223540643] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"241.846852ms","start":"2026-09-19T06:03:56.273964Z","end":"2026-09-19T06:03:56.515811Z","steps":["trace[1223540643] 'process raft request' (duration: 241.501717ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:56.516014Z","caller":"traceutil/trace.go:171","msg":"trace[204427368] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"242.665866ms","start":"2026-09-19T06:03:56.273336Z","end":"2026-09-19T06:03:56.516002Z","steps":["trace[204427368] 'process raft request' (duration: 106.228165ms)","trace[204427368] 'compare' (duration: 135.315024ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:56.516008Z","caller":"traceutil/trace.go:171","msg":"trace[1185397099] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"242.063736ms","start":"2026-09-19T06:03:56.273912Z","end":"2026-09-19T06:03:56.515976Z","steps":["trace[1185397099] 'process raft request' (duration: 241.460037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.831385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.338142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:56.831656Z","caller":"traceutil/trace.go:171","msg":"trace[1015133395] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"311.274348ms","start":"2026-09-19T06:03:56.520333Z","end":"2026-09-19T06:03:56.831607Z","steps":["trace[1015133395] 'process raft request' (duration: 96.643601ms)","trace[1015133395] 'compare' (duration: 214.147299ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:56.831633Z","caller":"traceutil/trace.go:171","msg":"trace[946885369] linearizableReadLoop","detail":"{readStateIndex:2633; appliedIndex:2631; }","duration":"310.951743ms","start":"2026-09-19T06:03:56.52061Z","end":"2026-09-19T06:03:56.831561Z","steps":["trace[946885369] 'read index received' (duration: 96.491859ms)","trace[946885369] 'applied index is now lower than readState.Index' (duration: 214.458954ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:56.8317Z","caller":"traceutil/trace.go:171","msg":"trace[584933757] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"311.288478ms","start":"2026-09-19T06:03:56.520357Z","end":"2026-09-19T06:03:56.831646Z","steps":["trace[584933757] 'process raft request' (duration: 311.119286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.831801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:56.520312Z","time spent":"311.42322ms","remote":"127.0.0.1:38906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":948,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:03:56.83186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:56.520341Z","time spent":"311.43891ms","remote":"127.0.0.1:38906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:03:56.832042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak.18d6a3a63aa9f02f\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-19T06:03:56.832124Z","caller":"traceutil/trace.go:171","msg":"trace[358163731] range","detail":"{range_begin:/registry/events/auth-system/keycloak.18d6a3a63aa9f02f; range_end:; response_count:1; response_revision:2534; }","duration":"311.499841ms","start":"2026-09-19T06:03:56.520574Z","end":"2026-09-19T06:03:56.832074Z","steps":["trace[358163731] 'agreement among raft nodes before linearized reading' (duration: 311.180656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.832158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:56.520561Z","time spent":"311.588022ms","remote":"127.0.0.1:38900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":690,"request content":"key:\"/registry/events/auth-system/keycloak.18d6a3a63aa9f02f\" "} {"level":"info","ts":"2026-09-19T06:03:56.855632Z","caller":"traceutil/trace.go:171","msg":"trace[1578082834] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"163.824099ms","start":"2026-09-19T06:03:56.691785Z","end":"2026-09-19T06:03:56.855609Z","steps":["trace[1578082834] 'process raft request' (duration: 163.628646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.855813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.776544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T06:03:56.855914Z","caller":"traceutil/trace.go:171","msg":"trace[904014737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2535; }","duration":"144.968877ms","start":"2026-09-19T06:03:56.71093Z","end":"2026-09-19T06:03:56.855898Z","steps":["trace[904014737] 'agreement among raft nodes before linearized reading' (duration: 144.711133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:56.856066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.008978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T06:03:56.856196Z","caller":"traceutil/trace.go:171","msg":"trace[218446409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2535; }","duration":"155.186391ms","start":"2026-09-19T06:03:56.70099Z","end":"2026-09-19T06:03:56.856176Z","steps":["trace[218446409] 'agreement among raft nodes before linearized reading' (duration: 154.596162ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:57.046232Z","caller":"traceutil/trace.go:171","msg":"trace[1190694413] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"208.476485ms","start":"2026-09-19T06:03:56.837732Z","end":"2026-09-19T06:03:57.046208Z","steps":["trace[1190694413] 'process raft request' (duration: 208.421124ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:57.046294Z","caller":"traceutil/trace.go:171","msg":"trace[1725148599] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"211.610186ms","start":"2026-09-19T06:03:56.834648Z","end":"2026-09-19T06:03:57.046258Z","steps":["trace[1725148599] 'process raft request' (duration: 161.024545ms)","trace[1725148599] 'compare' (duration: 50.324557ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:57.103053Z","caller":"traceutil/trace.go:171","msg":"trace[328689392] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"243.568438ms","start":"2026-09-19T06:03:56.859456Z","end":"2026-09-19T06:03:57.103025Z","steps":["trace[328689392] 'process raft request' (duration: 243.367794ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:57.103052Z","caller":"traceutil/trace.go:171","msg":"trace[1301736363] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"241.392674ms","start":"2026-09-19T06:03:56.861635Z","end":"2026-09-19T06:03:57.103027Z","steps":["trace[1301736363] 'process raft request' (duration: 241.311052ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:57.443453Z","caller":"traceutil/trace.go:171","msg":"trace[1189041509] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"340.429586ms","start":"2026-09-19T06:03:57.102999Z","end":"2026-09-19T06:03:57.443429Z","steps":["trace[1189041509] 'read index received' (duration: 340.393975ms)","trace[1189041509] 'applied index is now lower than readState.Index' (duration: 34.471µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:57.443641Z","caller":"traceutil/trace.go:171","msg":"trace[2038404023] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"392.49667ms","start":"2026-09-19T06:03:57.051012Z","end":"2026-09-19T06:03:57.443508Z","steps":["trace[2038404023] 'process raft request' (duration: 392.046593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:57.443797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.90294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.34\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-19T06:03:57.44382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.050988Z","time spent":"392.747174ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1354,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:03:57.443875Z","caller":"traceutil/trace.go:171","msg":"trace[505632933] range","detail":"{range_begin:/registry/masterleases/162.253.55.34; range_end:; response_count:1; response_revision:2540; }","duration":"377.017492ms","start":"2026-09-19T06:03:57.066838Z","end":"2026-09-19T06:03:57.443855Z","steps":["trace[505632933] 'agreement among raft nodes before linearized reading' (duration: 376.725738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:57.443915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.066826Z","time spent":"377.081183ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.34\" "} {"level":"info","ts":"2026-09-19T06:03:57.775756Z","caller":"traceutil/trace.go:171","msg":"trace[910128658] linearizableReadLoop","detail":"{readStateIndex:2641; appliedIndex:2639; }","duration":"332.187725ms","start":"2026-09-19T06:03:57.443547Z","end":"2026-09-19T06:03:57.775735Z","steps":["trace[910128658] 'read index received' (duration: 331.965211ms)","trace[910128658] 'applied index is now lower than readState.Index' (duration: 221.734µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:57.775832Z","caller":"traceutil/trace.go:171","msg":"trace[1424604621] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"651.082865ms","start":"2026-09-19T06:03:57.124731Z","end":"2026-09-19T06:03:57.775814Z","steps":["trace[1424604621] 'process raft request' (duration: 650.957543ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:57.776027Z","caller":"traceutil/trace.go:171","msg":"trace[416075101] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"669.606442ms","start":"2026-09-19T06:03:57.106385Z","end":"2026-09-19T06:03:57.775992Z","steps":["trace[416075101] 'process raft request' (duration: 669.117944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:57.776161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.617168ms","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":"warn","ts":"2026-09-19T06:03:57.776125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.142628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:03:57.776359Z","caller":"traceutil/trace.go:171","msg":"trace[161918700] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2542; }","duration":"658.509283ms","start":"2026-09-19T06:03:57.117837Z","end":"2026-09-19T06:03:57.776347Z","steps":["trace[161918700] 'agreement among raft nodes before linearized reading' (duration: 658.094947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:57.776353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.124694Z","time spent":"651.34965ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:03:57.776252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.106348Z","time spent":"669.811324ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":126926,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:03:57.776515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.117824Z","time spent":"658.664066ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":23,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-09-19T06:03:57.776252Z","caller":"traceutil/trace.go:171","msg":"trace[781964817] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2542; }","duration":"597.767049ms","start":"2026-09-19T06:03:57.178461Z","end":"2026-09-19T06:03:57.776228Z","steps":["trace[781964817] 'agreement among raft nodes before linearized reading' (duration: 597.440474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:57.777432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.178438Z","time spent":"598.957049ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-19T06:03:58.074281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.01625ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-09-19T06:03:58.074552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.445527Z","time spent":"629.016839ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-19T06:03:58.074701Z","caller":"traceutil/trace.go:171","msg":"trace[2043227615] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"293.802956ms","start":"2026-09-19T06:03:57.780876Z","end":"2026-09-19T06:03:58.074679Z","steps":["trace[2043227615] 'process raft request' (duration: 293.744215ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.074865Z","caller":"traceutil/trace.go:171","msg":"trace[20323219] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"626.371578ms","start":"2026-09-19T06:03:57.448449Z","end":"2026-09-19T06:03:58.074821Z","steps":["trace[20323219] 'process raft request' (duration: 626.070362ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.074992Z","caller":"traceutil/trace.go:171","msg":"trace[649093032] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"296.950517ms","start":"2026-09-19T06:03:57.77803Z","end":"2026-09-19T06:03:58.074981Z","steps":["trace[649093032] 'process raft request' (duration: 296.54954ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.075024Z","caller":"traceutil/trace.go:171","msg":"trace[585284468] linearizableReadLoop","detail":"{readStateIndex:2644; appliedIndex:2641; }","duration":"299.041491ms","start":"2026-09-19T06:03:57.775879Z","end":"2026-09-19T06:03:58.07492Z","steps":["trace[585284468] 'read index received' (duration: 180.35376ms)","trace[585284468] 'applied index is now lower than readState.Index' (duration: 118.677941ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:58.074973Z","caller":"traceutil/trace.go:171","msg":"trace[189595090] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"626.50465ms","start":"2026-09-19T06:03:57.448448Z","end":"2026-09-19T06:03:58.074953Z","steps":["trace[189595090] 'process raft request' (duration: 625.92906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.075179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.448435Z","time spent":"626.54665ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:03:58.075284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.448428Z","time spent":"626.787414ms","remote":"127.0.0.1:38984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:03:58.075342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.279526ms","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-09-19T06:03:58.075401Z","caller":"traceutil/trace.go:171","msg":"trace[1485799912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2546; }","duration":"442.379698ms","start":"2026-09-19T06:03:57.633003Z","end":"2026-09-19T06:03:58.075383Z","steps":["trace[1485799912] 'agreement among raft nodes before linearized reading' (duration: 442.102544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.075451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.960776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-19T06:03:58.07546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.632986Z","time spent":"442.458519ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T06:03:58.075495Z","caller":"traceutil/trace.go:171","msg":"trace[2045916668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2546; }","duration":"519.044698ms","start":"2026-09-19T06:03:57.556436Z","end":"2026-09-19T06:03:58.075481Z","steps":["trace[2045916668] 'agreement among raft nodes before linearized reading' (duration: 518.891885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.075536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.556418Z","time spent":"519.108879ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T06:03:58.080171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.796794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126940"} {"level":"info","ts":"2026-09-19T06:03:58.080274Z","caller":"traceutil/trace.go:171","msg":"trace[435032512] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2546; }","duration":"302.097989ms","start":"2026-09-19T06:03:57.778154Z","end":"2026-09-19T06:03:58.080252Z","steps":["trace[435032512] 'agreement among raft nodes before linearized reading' (duration: 297.257603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.080462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:57.778139Z","time spent":"302.1767ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":126962,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-09-19T06:03:58.409352Z","caller":"traceutil/trace.go:171","msg":"trace[181076209] linearizableReadLoop","detail":"{readStateIndex:2653; appliedIndex:2652; }","duration":"245.289765ms","start":"2026-09-19T06:03:58.164023Z","end":"2026-09-19T06:03:58.409313Z","steps":["trace[181076209] 'read index received' (duration: 70.791µs)","trace[181076209] 'applied index is now lower than readState.Index' (duration: 245.217304ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:58.409673Z","caller":"traceutil/trace.go:171","msg":"trace[1634630752] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"245.675141ms","start":"2026-09-19T06:03:58.163979Z","end":"2026-09-19T06:03:58.409654Z","steps":["trace[1634630752] 'process raft request' (duration: 151.054727ms)","trace[1634630752] 'compare' (duration: 93.866512ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:58.409847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.750111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-19T06:03:58.409926Z","caller":"traceutil/trace.go:171","msg":"trace[1534991768] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2553; }","duration":"245.860984ms","start":"2026-09-19T06:03:58.164046Z","end":"2026-09-19T06:03:58.409907Z","steps":["trace[1534991768] 'agreement among raft nodes before linearized reading' (duration: 245.62952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.409997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.952665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:03:58.410088Z","caller":"traceutil/trace.go:171","msg":"trace[1695685502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2553; }","duration":"246.071657ms","start":"2026-09-19T06:03:58.163979Z","end":"2026-09-19T06:03:58.410051Z","steps":["trace[1695685502] 'agreement among raft nodes before linearized reading' (duration: 245.951925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.410487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.441515ms","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-09-19T06:03:58.410828Z","caller":"traceutil/trace.go:171","msg":"trace[791463771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2553; }","duration":"174.022334ms","start":"2026-09-19T06:03:58.23679Z","end":"2026-09-19T06:03:58.410812Z","steps":["trace[791463771] 'agreement among raft nodes before linearized reading' (duration: 173.213721ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.561783Z","caller":"traceutil/trace.go:171","msg":"trace[1569380077] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"144.325268ms","start":"2026-09-19T06:03:58.417433Z","end":"2026-09-19T06:03:58.561758Z","steps":["trace[1569380077] 'process raft request' (duration: 144.233227ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.561944Z","caller":"traceutil/trace.go:171","msg":"trace[1052590908] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"146.604446ms","start":"2026-09-19T06:03:58.415316Z","end":"2026-09-19T06:03:58.561921Z","steps":["trace[1052590908] 'process raft request' (duration: 110.02635ms)","trace[1052590908] 'compare' (duration: 36.069398ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:58.562006Z","caller":"traceutil/trace.go:171","msg":"trace[1087844130] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"145.688981ms","start":"2026-09-19T06:03:58.416306Z","end":"2026-09-19T06:03:58.561995Z","steps":["trace[1087844130] 'process raft request' (duration: 145.261364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.79799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.099718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:03:58.798119Z","caller":"traceutil/trace.go:171","msg":"trace[1116539947] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"240.907624ms","start":"2026-09-19T06:03:58.557193Z","end":"2026-09-19T06:03:58.7981Z","steps":["trace[1116539947] 'process raft request' (duration: 114.612463ms)","trace[1116539947] 'compare' (duration: 125.962756ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:58.835053Z","caller":"traceutil/trace.go:171","msg":"trace[1130500318] linearizableReadLoop","detail":"{readStateIndex:2660; appliedIndex:2658; }","duration":"261.886861ms","start":"2026-09-19T06:03:58.57314Z","end":"2026-09-19T06:03:58.835027Z","steps":["trace[1130500318] 'read index received' (duration: 98.67398ms)","trace[1130500318] 'applied index is now lower than readState.Index' (duration: 163.211841ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:58.835148Z","caller":"traceutil/trace.go:171","msg":"trace[175153390] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"263.235331ms","start":"2026-09-19T06:03:58.571892Z","end":"2026-09-19T06:03:58.835127Z","steps":["trace[175153390] 'process raft request' (duration: 263.012948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:58.835312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.150265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T06:03:58.835344Z","caller":"traceutil/trace.go:171","msg":"trace[488303] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2560; }","duration":"262.230835ms","start":"2026-09-19T06:03:58.573103Z","end":"2026-09-19T06:03:58.835333Z","steps":["trace[488303] 'agreement among raft nodes before linearized reading' (duration: 262.032372ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:58.960792Z","caller":"traceutil/trace.go:171","msg":"trace[951960381] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"100.354835ms","start":"2026-09-19T06:03:58.86039Z","end":"2026-09-19T06:03:58.960745Z","steps":["trace[951960381] 'process raft request' (duration: 100.082441ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.22965Z","caller":"traceutil/trace.go:171","msg":"trace[51803514] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"286.958935ms","start":"2026-09-19T06:03:58.942678Z","end":"2026-09-19T06:03:59.229637Z","steps":["trace[51803514] 'process raft request' (duration: 286.889894ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.229659Z","caller":"traceutil/trace.go:171","msg":"trace[1853381654] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"296.803932ms","start":"2026-09-19T06:03:58.932806Z","end":"2026-09-19T06:03:59.22961Z","steps":["trace[1853381654] 'process raft request' (duration: 296.64709ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.230018Z","caller":"traceutil/trace.go:171","msg":"trace[289513214] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2662; }","duration":"122.753561ms","start":"2026-09-19T06:03:59.107223Z","end":"2026-09-19T06:03:59.229977Z","steps":["trace[289513214] 'read index received' (duration: 122.253343ms)","trace[289513214] 'applied index is now lower than readState.Index' (duration: 499.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:03:59.230027Z","caller":"traceutil/trace.go:171","msg":"trace[458902108] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"285.545952ms","start":"2026-09-19T06:03:58.944445Z","end":"2026-09-19T06:03:59.229991Z","steps":["trace[458902108] 'process raft request' (duration: 285.356879ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.230237Z","caller":"traceutil/trace.go:171","msg":"trace[737827255] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"286.352704ms","start":"2026-09-19T06:03:58.943829Z","end":"2026-09-19T06:03:59.230181Z","steps":["trace[737827255] 'process raft request' (duration: 285.774375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:59.230411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.182067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T06:03:59.23047Z","caller":"traceutil/trace.go:171","msg":"trace[2053483679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2567; }","duration":"123.29129ms","start":"2026-09-19T06:03:59.107163Z","end":"2026-09-19T06:03:59.230454Z","steps":["trace[2053483679] 'agreement among raft nodes before linearized reading' (duration: 123.116547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:59.230481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.603739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T06:03:59.230537Z","caller":"traceutil/trace.go:171","msg":"trace[219891767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2567; }","duration":"122.69715ms","start":"2026-09-19T06:03:59.107827Z","end":"2026-09-19T06:03:59.230524Z","steps":["trace[219891767] 'agreement among raft nodes before linearized reading' (duration: 122.540787ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.230557Z","caller":"traceutil/trace.go:171","msg":"trace[1563639398] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"285.138456ms","start":"2026-09-19T06:03:58.945397Z","end":"2026-09-19T06:03:59.230535Z","steps":["trace[1563639398] 'process raft request' (duration: 284.512796ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.412579Z","caller":"traceutil/trace.go:171","msg":"trace[1696991708] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"178.300528ms","start":"2026-09-19T06:03:59.234249Z","end":"2026-09-19T06:03:59.41255Z","steps":["trace[1696991708] 'process raft request' (duration: 169.198533ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.672152Z","caller":"traceutil/trace.go:171","msg":"trace[1796969525] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"431.719855ms","start":"2026-09-19T06:03:59.240415Z","end":"2026-09-19T06:03:59.672135Z","steps":["trace[1796969525] 'process raft request' (duration: 430.741949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:59.672245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:59.240397Z","time spent":"431.795466ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:03:59.672301Z","caller":"traceutil/trace.go:171","msg":"trace[266928036] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"431.599104ms","start":"2026-09-19T06:03:59.240683Z","end":"2026-09-19T06:03:59.672282Z","steps":["trace[266928036] 'process raft request' (duration: 431.455821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:59.672399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:59.240673Z","time spent":"431.672625ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:03:59.672458Z","caller":"traceutil/trace.go:171","msg":"trace[60155169] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"424.883506ms","start":"2026-09-19T06:03:59.247557Z","end":"2026-09-19T06:03:59.672441Z","steps":["trace[60155169] 'process raft request' (duration: 424.661633ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.672426Z","caller":"traceutil/trace.go:171","msg":"trace[2075723948] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2667; }","duration":"427.382746ms","start":"2026-09-19T06:03:59.245012Z","end":"2026-09-19T06:03:59.672395Z","steps":["trace[2075723948] 'read index received' (duration: 158.494302ms)","trace[2075723948] 'applied index is now lower than readState.Index' (duration: 268.886184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:03:59.672596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.58192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:6816"} {"level":"info","ts":"2026-09-19T06:03:59.67262Z","caller":"traceutil/trace.go:171","msg":"trace[3388045] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:2571; }","duration":"427.648121ms","start":"2026-09-19T06:03:59.244966Z","end":"2026-09-19T06:03:59.672614Z","steps":["trace[3388045] 'agreement among raft nodes before linearized reading' (duration: 427.554159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:03:59.672536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:59.247527Z","time spent":"424.969288ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:03:59.672641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:03:59.244954Z","time spent":"427.681411ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":6838,"request content":"key:\"/registry/pods/auth-system/keycloak-0\" "} {"level":"warn","ts":"2026-09-19T06:03:59.672845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.970445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-09-19T06:03:59.672909Z","caller":"traceutil/trace.go:171","msg":"trace[299766252] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:2571; }","duration":"197.088067ms","start":"2026-09-19T06:03:59.475805Z","end":"2026-09-19T06:03:59.672893Z","steps":["trace[299766252] 'agreement among raft nodes before linearized reading' (duration: 196.856614ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.832275Z","caller":"traceutil/trace.go:171","msg":"trace[1163746845] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"147.907233ms","start":"2026-09-19T06:03:59.684337Z","end":"2026-09-19T06:03:59.832244Z","steps":["trace[1163746845] 'process raft request' (duration: 147.697959ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.953336Z","caller":"traceutil/trace.go:171","msg":"trace[1097684117] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"172.641838ms","start":"2026-09-19T06:03:59.780674Z","end":"2026-09-19T06:03:59.953316Z","steps":["trace[1097684117] 'process raft request' (duration: 172.201281ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:03:59.953545Z","caller":"traceutil/trace.go:171","msg":"trace[878362942] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"154.552879ms","start":"2026-09-19T06:03:59.798983Z","end":"2026-09-19T06:03:59.953536Z","steps":["trace[878362942] 'process raft request' (duration: 154.01241ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:00.298284Z","caller":"traceutil/trace.go:171","msg":"trace[2025874426] linearizableReadLoop","detail":"{readStateIndex:2678; appliedIndex:2676; }","duration":"122.35714ms","start":"2026-09-19T06:04:00.175902Z","end":"2026-09-19T06:04:00.29826Z","steps":["trace[2025874426] 'read index received' (duration: 33.428272ms)","trace[2025874426] 'applied index is now lower than readState.Index' (duration: 88.927408ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:00.29846Z","caller":"traceutil/trace.go:171","msg":"trace[1671514801] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"122.651745ms","start":"2026-09-19T06:04:00.175782Z","end":"2026-09-19T06:04:00.298434Z","steps":["trace[1671514801] 'process raft request' (duration: 109.7386ms)","trace[1671514801] 'compare' (duration: 12.514619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:00.298509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.565643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-19T06:04:00.298546Z","caller":"traceutil/trace.go:171","msg":"trace[1709551390] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2579; }","duration":"122.651595ms","start":"2026-09-19T06:04:00.175884Z","end":"2026-09-19T06:04:00.298535Z","steps":["trace[1709551390] 'agreement among raft nodes before linearized reading' (duration: 122.481242ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:00.298524Z","caller":"traceutil/trace.go:171","msg":"trace[919167181] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"110.822136ms","start":"2026-09-19T06:04:00.187669Z","end":"2026-09-19T06:04:00.298492Z","steps":["trace[919167181] 'process raft request' (duration: 110.534392ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:00.447436Z","caller":"traceutil/trace.go:171","msg":"trace[1572551328] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"137.571724ms","start":"2026-09-19T06:04:00.309839Z","end":"2026-09-19T06:04:00.447411Z","steps":["trace[1572551328] 'process raft request' (duration: 103.788705ms)","trace[1572551328] 'compare' (duration: 33.586676ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:00.605736Z","caller":"traceutil/trace.go:171","msg":"trace[1090117232] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"118.408979ms","start":"2026-09-19T06:04:00.487298Z","end":"2026-09-19T06:04:00.605707Z","steps":["trace[1090117232] 'process raft request' (duration: 98.908667ms)","trace[1090117232] 'compare' (duration: 19.249028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:01.3167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.168413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:04:01.316804Z","caller":"traceutil/trace.go:171","msg":"trace[371647235] linearizableReadLoop","detail":"{readStateIndex:2686; appliedIndex:2685; }","duration":"255.6746ms","start":"2026-09-19T06:04:01.061112Z","end":"2026-09-19T06:04:01.316787Z","steps":["trace[371647235] 'read index received' (duration: 108.308714ms)","trace[371647235] 'applied index is now lower than readState.Index' (duration: 147.364736ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:01.316867Z","caller":"traceutil/trace.go:171","msg":"trace[1128907424] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"335.853977ms","start":"2026-09-19T06:04:00.980968Z","end":"2026-09-19T06:04:01.316822Z","steps":["trace[1128907424] 'process raft request' (duration: 188.480931ms)","trace[1128907424] 'compare' (duration: 147.025451ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:01.316933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.824812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-09-19T06:04:01.31696Z","caller":"traceutil/trace.go:171","msg":"trace[549718079] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2586; }","duration":"255.880593ms","start":"2026-09-19T06:04:01.06107Z","end":"2026-09-19T06:04:01.316951Z","steps":["trace[549718079] 'agreement among raft nodes before linearized reading' (duration: 255.760661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:01.317087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:00.980948Z","time spent":"336.010509ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:01.485295Z","caller":"traceutil/trace.go:171","msg":"trace[1468499748] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"152.639391ms","start":"2026-09-19T06:04:01.332623Z","end":"2026-09-19T06:04:01.485262Z","steps":["trace[1468499748] 'process raft request' (duration: 85.862348ms)","trace[1468499748] 'compare' (duration: 66.530699ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:01.485412Z","caller":"traceutil/trace.go:171","msg":"trace[1230181855] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"125.206574ms","start":"2026-09-19T06:04:01.360189Z","end":"2026-09-19T06:04:01.485395Z","steps":["trace[1230181855] 'process raft request' (duration: 125.007701ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:01.800921Z","caller":"traceutil/trace.go:171","msg":"trace[2125668462] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"119.869009ms","start":"2026-09-19T06:04:01.681022Z","end":"2026-09-19T06:04:01.800891Z","steps":["trace[2125668462] 'process raft request' (duration: 119.732007ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:01.826236Z","caller":"traceutil/trace.go:171","msg":"trace[1077460825] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"144.966468ms","start":"2026-09-19T06:04:01.681216Z","end":"2026-09-19T06:04:01.826182Z","steps":["trace[1077460825] 'process raft request' (duration: 144.756345ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.182308Z","caller":"traceutil/trace.go:171","msg":"trace[1665320806] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"113.426763ms","start":"2026-09-19T06:04:02.068838Z","end":"2026-09-19T06:04:02.182265Z","steps":["trace[1665320806] 'process raft request' (duration: 113.068068ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.331789Z","caller":"traceutil/trace.go:171","msg":"trace[1029596162] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"143.243546ms","start":"2026-09-19T06:04:02.188483Z","end":"2026-09-19T06:04:02.331727Z","steps":["trace[1029596162] 'process raft request' (duration: 130.526955ms)","trace[1029596162] 'compare' (duration: 12.365456ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:02.331814Z","caller":"traceutil/trace.go:171","msg":"trace[1587584057] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"143.242586ms","start":"2026-09-19T06:04:02.18854Z","end":"2026-09-19T06:04:02.331782Z","steps":["trace[1587584057] 'process raft request' (duration: 143.066623ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.33181Z","caller":"traceutil/trace.go:171","msg":"trace[1581654798] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"141.954676ms","start":"2026-09-19T06:04:02.189836Z","end":"2026-09-19T06:04:02.33179Z","steps":["trace[1581654798] 'process raft request' (duration: 141.871265ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.332006Z","caller":"traceutil/trace.go:171","msg":"trace[1509921484] linearizableReadLoop","detail":"{readStateIndex:2699; appliedIndex:2693; }","duration":"119.198424ms","start":"2026-09-19T06:04:02.212789Z","end":"2026-09-19T06:04:02.331987Z","steps":["trace[1509921484] 'read index received' (duration: 1.473353ms)","trace[1509921484] 'applied index is now lower than readState.Index' (duration: 117.724091ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:02.332206Z","caller":"traceutil/trace.go:171","msg":"trace[1942629523] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"142.294552ms","start":"2026-09-19T06:04:02.189854Z","end":"2026-09-19T06:04:02.332148Z","steps":["trace[1942629523] 'process raft request' (duration: 141.901406ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.332188Z","caller":"traceutil/trace.go:171","msg":"trace[1699400567] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"138.755295ms","start":"2026-09-19T06:04:02.193405Z","end":"2026-09-19T06:04:02.332161Z","steps":["trace[1699400567] 'process raft request' (duration: 138.490411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:02.332483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.676082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T06:04:02.332532Z","caller":"traceutil/trace.go:171","msg":"trace[818387291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2599; }","duration":"119.771583ms","start":"2026-09-19T06:04:02.212748Z","end":"2026-09-19T06:04:02.332519Z","steps":["trace[818387291] 'agreement among raft nodes before linearized reading' (duration: 119.534969ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.472185Z","caller":"traceutil/trace.go:171","msg":"trace[2092503546] linearizableReadLoop","detail":"{readStateIndex:2700; appliedIndex:2699; }","duration":"140.080256ms","start":"2026-09-19T06:04:02.332086Z","end":"2026-09-19T06:04:02.472167Z","steps":["trace[2092503546] 'read index received' (duration: 69.968422ms)","trace[2092503546] 'applied index is now lower than readState.Index' (duration: 70.110744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:02.472362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.992451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T06:04:02.472389Z","caller":"traceutil/trace.go:171","msg":"trace[155192974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2599; }","duration":"170.077112ms","start":"2026-09-19T06:04:02.302302Z","end":"2026-09-19T06:04:02.472379Z","steps":["trace[155192974] 'agreement among raft nodes before linearized reading' (duration: 169.93257ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.472559Z","caller":"traceutil/trace.go:171","msg":"trace[807842787] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"133.566082ms","start":"2026-09-19T06:04:02.338975Z","end":"2026-09-19T06:04:02.472542Z","steps":["trace[807842787] 'process raft request' (duration: 133.267007ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:02.472591Z","caller":"traceutil/trace.go:171","msg":"trace[425948147] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"128.388139ms","start":"2026-09-19T06:04:02.344192Z","end":"2026-09-19T06:04:02.47258Z","steps":["trace[425948147] 'process raft request' (duration: 128.298338ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:04.150771Z","caller":"traceutil/trace.go:171","msg":"trace[1446714250] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"193.414495ms","start":"2026-09-19T06:04:03.957321Z","end":"2026-09-19T06:04:04.150735Z","steps":["trace[1446714250] 'process raft request' (duration: 193.200091ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:04.300639Z","caller":"traceutil/trace.go:171","msg":"trace[315244876] linearizableReadLoop","detail":"{readStateIndex:2710; appliedIndex:2708; }","duration":"163.839767ms","start":"2026-09-19T06:04:04.136758Z","end":"2026-09-19T06:04:04.300598Z","steps":["trace[315244876] 'read index received' (duration: 13.772239ms)","trace[315244876] 'applied index is now lower than readState.Index' (duration: 150.064927ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:04.300841Z","caller":"traceutil/trace.go:171","msg":"trace[891103459] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"328.069218ms","start":"2026-09-19T06:04:03.972739Z","end":"2026-09-19T06:04:04.300808Z","steps":["trace[891103459] 'process raft request' (duration: 294.797631ms)","trace[891103459] 'compare' (duration: 32.85228ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:04.301037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:03.972709Z","time spent":"328.200999ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:04:04.300954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.165641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:04:04.301166Z","caller":"traceutil/trace.go:171","msg":"trace[1891105386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2609; }","duration":"164.441555ms","start":"2026-09-19T06:04:04.136704Z","end":"2026-09-19T06:04:04.301146Z","steps":["trace[1891105386] 'agreement among raft nodes before linearized reading' (duration: 164.158091ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:05.178908Z","caller":"traceutil/trace.go:171","msg":"trace[1978193219] linearizableReadLoop","detail":"{readStateIndex:2715; appliedIndex:2714; }","duration":"210.712464ms","start":"2026-09-19T06:04:04.968168Z","end":"2026-09-19T06:04:05.17888Z","steps":["trace[1978193219] 'read index received' (duration: 210.553242ms)","trace[1978193219] 'applied index is now lower than readState.Index' (duration: 158.012µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:05.178942Z","caller":"traceutil/trace.go:171","msg":"trace[541124273] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"370.325513ms","start":"2026-09-19T06:04:04.808592Z","end":"2026-09-19T06:04:05.178918Z","steps":["trace[541124273] 'process raft request' (duration: 370.12826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:05.179145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:04.808576Z","time spent":"370.446175ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:04:05.179396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.210313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T06:04:05.179547Z","caller":"traceutil/trace.go:171","msg":"trace[1641312320] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2614; }","duration":"211.347005ms","start":"2026-09-19T06:04:04.968099Z","end":"2026-09-19T06:04:05.179446Z","steps":["trace[1641312320] 'agreement among raft nodes before linearized reading' (duration: 210.870467ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:05.393949Z","caller":"traceutil/trace.go:171","msg":"trace[1621821594] linearizableReadLoop","detail":"{readStateIndex:2716; appliedIndex:2715; }","duration":"211.000947ms","start":"2026-09-19T06:04:05.18292Z","end":"2026-09-19T06:04:05.393921Z","steps":["trace[1621821594] 'read index received' (duration: 182.945433ms)","trace[1621821594] 'applied index is now lower than readState.Index' (duration: 28.054524ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:05.394087Z","caller":"traceutil/trace.go:171","msg":"trace[142467314] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"247.085928ms","start":"2026-09-19T06:04:05.146961Z","end":"2026-09-19T06:04:05.394047Z","steps":["trace[142467314] 'process raft request' (duration: 219.021144ms)","trace[142467314] 'compare' (duration: 27.82697ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:05.394224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.282991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-09-19T06:04:05.394315Z","caller":"traceutil/trace.go:171","msg":"trace[441764774] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2615; }","duration":"211.410544ms","start":"2026-09-19T06:04:05.182886Z","end":"2026-09-19T06:04:05.394297Z","steps":["trace[441764774] 'agreement among raft nodes before linearized reading' (duration: 211.146ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:06.021459Z","caller":"traceutil/trace.go:171","msg":"trace[1270805983] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"107.300046ms","start":"2026-09-19T06:04:05.914112Z","end":"2026-09-19T06:04:06.021412Z","steps":["trace[1270805983] 'process raft request' (duration: 72.493447ms)","trace[1270805983] 'compare' (duration: 34.615957ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:07.227402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.497242ms","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-09-19T06:04:07.227479Z","caller":"traceutil/trace.go:171","msg":"trace[1217132593] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2618; }","duration":"830.621484ms","start":"2026-09-19T06:04:06.396835Z","end":"2026-09-19T06:04:07.227456Z","steps":["trace[1217132593] 'range keys from in-memory index tree' (duration: 830.310149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.227517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.396814Z","time spent":"830.693775ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-19T06:04:07.227644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.716524ms","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-09-19T06:04:07.227724Z","caller":"traceutil/trace.go:171","msg":"trace[311737822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2618; }","duration":"709.844077ms","start":"2026-09-19T06:04:06.517864Z","end":"2026-09-19T06:04:07.227708Z","steps":["trace[311737822] 'range keys from in-memory index tree' (duration: 709.574782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.227762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.517846Z","time spent":"709.906617ms","remote":"127.0.0.1:39088","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-09-19T06:04:07.2278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.643919ms","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-09-19T06:04:07.227896Z","caller":"traceutil/trace.go:171","msg":"trace[1523093721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2618; }","duration":"646.78647ms","start":"2026-09-19T06:04:06.581085Z","end":"2026-09-19T06:04:07.227872Z","steps":["trace[1523093721] 'range keys from in-memory index tree' (duration: 646.442855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.227956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.581072Z","time spent":"646.871521ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T06:04:07.23927Z","caller":"traceutil/trace.go:171","msg":"trace[1866579858] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"503.941784ms","start":"2026-09-19T06:04:06.735311Z","end":"2026-09-19T06:04:07.239253Z","steps":["trace[1866579858] 'process raft request' (duration: 503.796881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.239387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.735289Z","time spent":"504.031475ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:07.350062Z","caller":"traceutil/trace.go:171","msg":"trace[331939884] transaction","detail":"{read_only:false; response_revision:2620; number_of_response:1; }","duration":"555.476365ms","start":"2026-09-19T06:04:06.794552Z","end":"2026-09-19T06:04:07.350029Z","steps":["trace[331939884] 'process raft request' (duration: 546.525124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.350276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.794528Z","time spent":"555.637598ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:07.350452Z","caller":"traceutil/trace.go:171","msg":"trace[1459633743] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"116.999905ms","start":"2026-09-19T06:04:07.233441Z","end":"2026-09-19T06:04:07.350441Z","steps":["trace[1459633743] 'process raft request' (duration: 116.502907ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:07.350536Z","caller":"traceutil/trace.go:171","msg":"trace[298889604] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"115.871368ms","start":"2026-09-19T06:04:07.234656Z","end":"2026-09-19T06:04:07.350527Z","steps":["trace[298889604] 'process raft request' (duration: 115.580933ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:07.350514Z","caller":"traceutil/trace.go:171","msg":"trace[328504835] linearizableReadLoop","detail":"{readStateIndex:2721; appliedIndex:2719; }","duration":"381.438677ms","start":"2026-09-19T06:04:06.969048Z","end":"2026-09-19T06:04:07.350487Z","steps":["trace[328504835] 'read index received' (duration: 270.137352ms)","trace[328504835] 'applied index is now lower than readState.Index' (duration: 111.300055ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:07.350678Z","caller":"traceutil/trace.go:171","msg":"trace[1795676692] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"115.96021ms","start":"2026-09-19T06:04:07.234701Z","end":"2026-09-19T06:04:07.350661Z","steps":["trace[1795676692] 'process raft request' (duration: 115.732916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.350854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.168175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.34\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-19T06:04:07.35091Z","caller":"traceutil/trace.go:171","msg":"trace[1633890862] range","detail":"{range_begin:/registry/masterleases/162.253.55.34; range_end:; response_count:1; response_revision:2623; }","duration":"283.277357ms","start":"2026-09-19T06:04:07.067617Z","end":"2026-09-19T06:04:07.350894Z","steps":["trace[1633890862] 'agreement among raft nodes before linearized reading' (duration: 283.116905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.351076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.011565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:04:07.351094Z","caller":"traceutil/trace.go:171","msg":"trace[1648299407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2623; }","duration":"214.066046ms","start":"2026-09-19T06:04:07.137022Z","end":"2026-09-19T06:04:07.351088Z","steps":["trace[1648299407] 'agreement among raft nodes before linearized reading' (duration: 214.035176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.351094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.734595ms","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-09-19T06:04:07.351152Z","caller":"traceutil/trace.go:171","msg":"trace[1817032419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2623; }","duration":"167.842107ms","start":"2026-09-19T06:04:07.183294Z","end":"2026-09-19T06:04:07.351136Z","steps":["trace[1817032419] 'agreement among raft nodes before linearized reading' (duration: 167.626224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.3512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.589915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:04:07.351219Z","caller":"traceutil/trace.go:171","msg":"trace[1787284869] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2623; }","duration":"254.649376ms","start":"2026-09-19T06:04:07.096564Z","end":"2026-09-19T06:04:07.351213Z","steps":["trace[1787284869] 'agreement among raft nodes before linearized reading' (duration: 254.606946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.35122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.177458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T06:04:07.351289Z","caller":"traceutil/trace.go:171","msg":"trace[1344254503] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2623; }","duration":"382.31528ms","start":"2026-09-19T06:04:06.968962Z","end":"2026-09-19T06:04:07.351277Z","steps":["trace[1344254503] 'agreement among raft nodes before linearized reading' (duration: 382.129497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.351318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:06.96894Z","time spent":"382.371721ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-09-19T06:04:07.529681Z","caller":"traceutil/trace.go:171","msg":"trace[1870992244] linearizableReadLoop","detail":"{readStateIndex:2726; appliedIndex:2725; }","duration":"174.232889ms","start":"2026-09-19T06:04:07.355421Z","end":"2026-09-19T06:04:07.529654Z","steps":["trace[1870992244] 'read index received' (duration: 54.301937ms)","trace[1870992244] 'applied index is now lower than readState.Index' (duration: 119.929692ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:07.529701Z","caller":"traceutil/trace.go:171","msg":"trace[1830624042] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"174.34786ms","start":"2026-09-19T06:04:07.355329Z","end":"2026-09-19T06:04:07.529677Z","steps":["trace[1830624042] 'process raft request' (duration: 173.996933ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:07.5299Z","caller":"traceutil/trace.go:171","msg":"trace[2112475958] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"129.064765ms","start":"2026-09-19T06:04:07.400825Z","end":"2026-09-19T06:04:07.52989Z","steps":["trace[2112475958] 'process raft request' (duration: 128.776471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:07.530127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.686195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-09-19T06:04:07.530176Z","caller":"traceutil/trace.go:171","msg":"trace[737171189] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2625; }","duration":"174.780057ms","start":"2026-09-19T06:04:07.355382Z","end":"2026-09-19T06:04:07.530162Z","steps":["trace[737171189] 'agreement among raft nodes before linearized reading' (duration: 174.624405ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:07.554551Z","caller":"traceutil/trace.go:171","msg":"trace[1795508946] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"143.722956ms","start":"2026-09-19T06:04:07.410796Z","end":"2026-09-19T06:04:07.554519Z","steps":["trace[1795508946] 'process raft request' (duration: 143.546493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:08.194335Z","caller":"traceutil/trace.go:171","msg":"trace[701262417] linearizableReadLoop","detail":"{readStateIndex:2729; appliedIndex:2728; }","duration":"166.792346ms","start":"2026-09-19T06:04:08.027514Z","end":"2026-09-19T06:04:08.194306Z","steps":["trace[701262417] 'read index received' (duration: 166.525232ms)","trace[701262417] 'applied index is now lower than readState.Index' (duration: 265.704µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:08.194436Z","caller":"traceutil/trace.go:171","msg":"trace[1115671367] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"287.869595ms","start":"2026-09-19T06:04:07.906545Z","end":"2026-09-19T06:04:08.194414Z","steps":["trace[1115671367] 'process raft request' (duration: 287.56541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:08.194741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.209773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T06:04:08.1948Z","caller":"traceutil/trace.go:171","msg":"trace[915313248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2627; }","duration":"167.304895ms","start":"2026-09-19T06:04:08.027474Z","end":"2026-09-19T06:04:08.194779Z","steps":["trace[915313248] 'agreement among raft nodes before linearized reading' (duration: 166.938109ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:08.738509Z","caller":"traceutil/trace.go:171","msg":"trace[597255367] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"400.7607ms","start":"2026-09-19T06:04:08.337723Z","end":"2026-09-19T06:04:08.738483Z","steps":["trace[597255367] 'process raft request' (duration: 400.489506ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:08.738501Z","caller":"traceutil/trace.go:171","msg":"trace[164878301] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"539.20545ms","start":"2026-09-19T06:04:08.199258Z","end":"2026-09-19T06:04:08.738463Z","steps":["trace[164878301] 'process raft request' (duration: 462.868558ms)","trace[164878301] 'compare' (duration: 75.927426ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:08.738673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:08.199239Z","time spent":"539.350173ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:04:08.739584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:08.337677Z","time spent":"400.894803ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:10.853665Z","caller":"traceutil/trace.go:171","msg":"trace[43509955] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"105.07846ms","start":"2026-09-19T06:04:10.748557Z","end":"2026-09-19T06:04:10.853635Z","steps":["trace[43509955] 'process raft request' (duration: 69.267638ms)","trace[43509955] 'compare' (duration: 35.627209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:04:11.107113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.144073ms","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-09-19T06:04:11.107188Z","caller":"traceutil/trace.go:171","msg":"trace[2114979862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2639; }","duration":"132.299836ms","start":"2026-09-19T06:04:10.97487Z","end":"2026-09-19T06:04:11.107169Z","steps":["trace[2114979862] 'count revisions from in-memory index tree' (duration: 132.024162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:11.107591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.663875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T06:04:11.107656Z","caller":"traceutil/trace.go:171","msg":"trace[1585359529] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2639; }","duration":"138.765547ms","start":"2026-09-19T06:04:10.968868Z","end":"2026-09-19T06:04:11.107634Z","steps":["trace[1585359529] 'range keys from in-memory index tree' (duration: 138.475092ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:40.503567Z","caller":"traceutil/trace.go:171","msg":"trace[2093070615] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"118.211662ms","start":"2026-09-19T06:04:40.385318Z","end":"2026-09-19T06:04:40.503529Z","steps":["trace[2093070615] 'process raft request' (duration: 118.076576ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:45.92355Z","caller":"traceutil/trace.go:171","msg":"trace[2010672823] linearizableReadLoop","detail":"{readStateIndex:2940; appliedIndex:2939; }","duration":"156.708437ms","start":"2026-09-19T06:04:45.766808Z","end":"2026-09-19T06:04:45.923517Z","steps":["trace[2010672823] 'read index received' (duration: 156.373013ms)","trace[2010672823] 'applied index is now lower than readState.Index' (duration: 333.814µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:45.923604Z","caller":"traceutil/trace.go:171","msg":"trace[970386487] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"168.45959ms","start":"2026-09-19T06:04:45.755107Z","end":"2026-09-19T06:04:45.923567Z","steps":["trace[970386487] 'process raft request' (duration: 168.22945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:45.923829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.019559ms","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-09-19T06:04:45.923888Z","caller":"traceutil/trace.go:171","msg":"trace[773027699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2831; }","duration":"157.141694ms","start":"2026-09-19T06:04:45.766731Z","end":"2026-09-19T06:04:45.923873Z","steps":["trace[773027699] 'agreement among raft nodes before linearized reading' (duration: 156.946596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:45.924078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.290992ms","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":"warn","ts":"2026-09-19T06:04:45.924188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.587592ms","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-09-19T06:04:45.924168Z","caller":"traceutil/trace.go:171","msg":"trace[756988715] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2831; }","duration":"132.415637ms","start":"2026-09-19T06:04:45.791735Z","end":"2026-09-19T06:04:45.924151Z","steps":["trace[756988715] 'agreement among raft nodes before linearized reading' (duration: 132.156647ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:45.924276Z","caller":"traceutil/trace.go:171","msg":"trace[1005808059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2831; }","duration":"123.661574ms","start":"2026-09-19T06:04:45.800569Z","end":"2026-09-19T06:04:45.924231Z","steps":["trace[1005808059] 'agreement among raft nodes before linearized reading' (duration: 123.488007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:45.924449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.76805ms","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-09-19T06:04:45.924494Z","caller":"traceutil/trace.go:171","msg":"trace[388079757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2831; }","duration":"126.835622ms","start":"2026-09-19T06:04:45.797646Z","end":"2026-09-19T06:04:45.924482Z","steps":["trace[388079757] 'agreement among raft nodes before linearized reading' (duration: 126.726478ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:46.187997Z","caller":"traceutil/trace.go:171","msg":"trace[584409876] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"258.380382ms","start":"2026-09-19T06:04:45.929588Z","end":"2026-09-19T06:04:46.187969Z","steps":["trace[584409876] 'process raft request' (duration: 159.083887ms)","trace[584409876] 'compare' (duration: 99.156309ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:46.235829Z","caller":"traceutil/trace.go:171","msg":"trace[2129846468] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"306.060265ms","start":"2026-09-19T06:04:45.92974Z","end":"2026-09-19T06:04:46.2358Z","steps":["trace[2129846468] 'process raft request' (duration: 305.811075ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:04:46.235915Z","caller":"traceutil/trace.go:171","msg":"trace[2094507449] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"306.036414ms","start":"2026-09-19T06:04:45.92984Z","end":"2026-09-19T06:04:46.235877Z","steps":["trace[2094507449] 'process raft request' (duration: 305.880008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:46.236057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.258141ms","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":"warn","ts":"2026-09-19T06:04:46.236099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:45.929719Z","time spent":"306.18841ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:46.236167Z","caller":"traceutil/trace.go:171","msg":"trace[1967865125] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2835; }","duration":"237.415788ms","start":"2026-09-19T06:04:45.998732Z","end":"2026-09-19T06:04:46.236147Z","steps":["trace[1967865125] 'agreement among raft nodes before linearized reading' (duration: 237.153898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:46.236211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:45.929833Z","time spent":"306.143718ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:04:46.235842Z","caller":"traceutil/trace.go:171","msg":"trace[1849582282] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2940; }","duration":"237.043903ms","start":"2026-09-19T06:04:45.998772Z","end":"2026-09-19T06:04:46.235816Z","steps":["trace[1849582282] 'read index received' (duration: 90.088126ms)","trace[1849582282] 'applied index is now lower than readState.Index' (duration: 146.954437ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:04:46.236397Z","caller":"traceutil/trace.go:171","msg":"trace[1991965251] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"306.239562ms","start":"2026-09-19T06:04:45.929848Z","end":"2026-09-19T06:04:46.236087Z","steps":["trace[1991965251] 'process raft request' (duration: 305.924349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:04:46.236627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:04:45.929831Z","time spent":"306.713032ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:04:46.236621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.266524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:04:46.236974Z","caller":"traceutil/trace.go:171","msg":"trace[273763703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2835; }","duration":"100.734973ms","start":"2026-09-19T06:04:46.136209Z","end":"2026-09-19T06:04:46.236944Z","steps":["trace[273763703] 'agreement among raft nodes before linearized reading' (duration: 100.297555ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:10.998382Z","caller":"traceutil/trace.go:171","msg":"trace[52674495] transaction","detail":"{read_only:false; response_revision:3648; number_of_response:1; }","duration":"131.228136ms","start":"2026-09-19T06:07:10.867116Z","end":"2026-09-19T06:07:10.998345Z","steps":["trace[52674495] 'process raft request' (duration: 60.456349ms)","trace[52674495] 'compare' (duration: 70.622363ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:11.001461Z","caller":"traceutil/trace.go:171","msg":"trace[1765272108] transaction","detail":"{read_only:false; response_revision:3652; number_of_response:1; }","duration":"131.224167ms","start":"2026-09-19T06:07:10.870211Z","end":"2026-09-19T06:07:11.001435Z","steps":["trace[1765272108] 'process raft request' (duration: 130.876157ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:11.001495Z","caller":"traceutil/trace.go:171","msg":"trace[1299634777] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"131.7297ms","start":"2026-09-19T06:07:10.869739Z","end":"2026-09-19T06:07:11.001469Z","steps":["trace[1299634777] 'process raft request' (duration: 131.308648ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:11.001589Z","caller":"traceutil/trace.go:171","msg":"trace[2049377377] transaction","detail":"{read_only:false; response_revision:3649; number_of_response:1; }","duration":"133.926954ms","start":"2026-09-19T06:07:10.867635Z","end":"2026-09-19T06:07:11.001562Z","steps":["trace[2049377377] 'process raft request' (duration: 133.281017ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:11.001628Z","caller":"traceutil/trace.go:171","msg":"trace[15340053] transaction","detail":"{read_only:false; response_revision:3650; number_of_response:1; }","duration":"133.640527ms","start":"2026-09-19T06:07:10.867887Z","end":"2026-09-19T06:07:11.001528Z","steps":["trace[15340053] 'process raft request' (duration: 133.116033ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:17.66271Z","caller":"traceutil/trace.go:171","msg":"trace[597261505] linearizableReadLoop","detail":"{readStateIndex:4222; appliedIndex:4221; }","duration":"121.77794ms","start":"2026-09-19T06:08:17.54091Z","end":"2026-09-19T06:08:17.662688Z","steps":["trace[597261505] 'read index received' (duration: 121.558065ms)","trace[597261505] 'applied index is now lower than readState.Index' (duration: 219.065µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:17.662766Z","caller":"traceutil/trace.go:171","msg":"trace[1921594630] transaction","detail":"{read_only:false; response_revision:4061; number_of_response:1; }","duration":"128.312383ms","start":"2026-09-19T06:08:17.53443Z","end":"2026-09-19T06:08:17.662743Z","steps":["trace[1921594630] 'process raft request' (duration: 128.115419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:17.663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.066147ms","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-09-19T06:08:17.663101Z","caller":"traceutil/trace.go:171","msg":"trace[965749113] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4061; }","duration":"122.206899ms","start":"2026-09-19T06:08:17.540868Z","end":"2026-09-19T06:08:17.663075Z","steps":["trace[965749113] 'agreement among raft nodes before linearized reading' (duration: 121.923592ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:18.092246Z","caller":"traceutil/trace.go:171","msg":"trace[2050735414] transaction","detail":"{read_only:false; response_revision:4074; number_of_response:1; }","duration":"110.856547ms","start":"2026-09-19T06:08:17.98137Z","end":"2026-09-19T06:08:18.092227Z","steps":["trace[2050735414] 'process raft request' (duration: 100.659753ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:53.218867Z","caller":"traceutil/trace.go:171","msg":"trace[539374779] transaction","detail":"{read_only:false; response_revision:4289; number_of_response:1; }","duration":"134.599581ms","start":"2026-09-19T06:08:53.084213Z","end":"2026-09-19T06:08:53.218813Z","steps":["trace[539374779] 'process raft request' (duration: 132.967776ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:07.224777Z","caller":"traceutil/trace.go:171","msg":"trace[84278288] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5026; }","duration":"118.77326ms","start":"2026-09-19T06:09:07.105981Z","end":"2026-09-19T06:09:07.224754Z","steps":["trace[84278288] 'read index received' (duration: 101.392031ms)","trace[84278288] 'applied index is now lower than readState.Index' (duration: 17.379679ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:07.225171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.171058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea7e2ba0-d09b-4147-8947-fd0893b2a8e4\" ","response":"range_response_count:1 size:4081"} {"level":"info","ts":"2026-09-19T06:09:07.225261Z","caller":"traceutil/trace.go:171","msg":"trace[1675131113] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea7e2ba0-d09b-4147-8947-fd0893b2a8e4; range_end:; response_count:1; response_revision:4839; }","duration":"119.282ms","start":"2026-09-19T06:09:07.105968Z","end":"2026-09-19T06:09:07.225249Z","steps":["trace[1675131113] 'agreement among raft nodes before linearized reading' (duration: 119.090376ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:10.504032Z","caller":"traceutil/trace.go:171","msg":"trace[115732183] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"131.589615ms","start":"2026-09-19T06:09:10.372421Z","end":"2026-09-19T06:09:10.504011Z","steps":["trace[115732183] 'process raft request' (duration: 131.442032ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:10.536572Z","caller":"traceutil/trace.go:171","msg":"trace[925891051] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"161.471769ms","start":"2026-09-19T06:09:10.375067Z","end":"2026-09-19T06:09:10.536539Z","steps":["trace[925891051] 'process raft request' (duration: 161.169633ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:10.536798Z","caller":"traceutil/trace.go:171","msg":"trace[189053021] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"161.356426ms","start":"2026-09-19T06:09:10.375406Z","end":"2026-09-19T06:09:10.536763Z","steps":["trace[189053021] 'process raft request' (duration: 161.210083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:16.936816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.336449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:09:16.937091Z","caller":"traceutil/trace.go:171","msg":"trace[1166277300] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"447.253273ms","start":"2026-09-19T06:09:16.489739Z","end":"2026-09-19T06:09:16.936992Z","steps":["trace[1166277300] 'process raft request' (duration: 204.661699ms)","trace[1166277300] 'compare' (duration: 242.215396ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:16.937349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.489721Z","time spent":"447.481079ms","remote":"127.0.0.1:38900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":936,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:09:16.937222Z","caller":"traceutil/trace.go:171","msg":"trace[771461107] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"443.653581ms","start":"2026-09-19T06:09:16.493548Z","end":"2026-09-19T06:09:16.937202Z","steps":["trace[771461107] 'process raft request' (duration: 443.364524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:16.937632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.493536Z","time spent":"444.036928ms","remote":"127.0.0.1:49790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:09:17.02088Z","caller":"traceutil/trace.go:171","msg":"trace[413563173] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5204; }","duration":"500.336317ms","start":"2026-09-19T06:09:16.520514Z","end":"2026-09-19T06:09:17.02085Z","steps":["trace[413563173] 'read index received' (duration: 173.958333ms)","trace[413563173] 'applied index is now lower than readState.Index' (duration: 326.376844ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:17.021195Z","caller":"traceutil/trace.go:171","msg":"trace[1075135821] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"505.235599ms","start":"2026-09-19T06:09:16.515946Z","end":"2026-09-19T06:09:17.021181Z","steps":["trace[1075135821] 'process raft request' (duration: 504.566214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.021302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.515925Z","time spent":"505.31005ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:17.021522Z","caller":"traceutil/trace.go:171","msg":"trace[1548041716] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"496.300096ms","start":"2026-09-19T06:09:16.525205Z","end":"2026-09-19T06:09:17.021506Z","steps":["trace[1548041716] 'process raft request' (duration: 495.451618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.021604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.525186Z","time spent":"496.374827ms","remote":"127.0.0.1:39092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:17.021834Z","caller":"traceutil/trace.go:171","msg":"trace[1708531086] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"496.559781ms","start":"2026-09-19T06:09:16.525263Z","end":"2026-09-19T06:09:17.021823Z","steps":["trace[1708531086] 'process raft request' (duration: 495.435427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.02192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.525255Z","time spent":"496.614222ms","remote":"127.0.0.1:38984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:17.022048Z","caller":"traceutil/trace.go:171","msg":"trace[485287280] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"494.024559ms","start":"2026-09-19T06:09:16.528014Z","end":"2026-09-19T06:09:17.022039Z","steps":["trace[485287280] 'process raft request' (duration: 492.731352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.022133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.528006Z","time spent":"494.068711ms","remote":"127.0.0.1:38984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:09:17.023345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.816128ms","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-09-19T06:09:17.022421Z","caller":"traceutil/trace.go:171","msg":"trace[26339479] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"452.834987ms","start":"2026-09-19T06:09:16.569575Z","end":"2026-09-19T06:09:17.02241Z","steps":["trace[26339479] 'process raft request' (duration: 451.204544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.023495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.56956Z","time spent":"453.879369ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:17.023407Z","caller":"traceutil/trace.go:171","msg":"trace[1525890374] 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:5018; }","duration":"502.92266ms","start":"2026-09-19T06:09:16.520467Z","end":"2026-09-19T06:09:17.02339Z","steps":["trace[1525890374] 'agreement among raft nodes before linearized reading' (duration: 502.731586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.023681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.520453Z","time spent":"503.217286ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-09-19T06:09:17.073955Z","caller":"traceutil/trace.go:171","msg":"trace[1237376808] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"255.650909ms","start":"2026-09-19T06:09:16.818267Z","end":"2026-09-19T06:09:17.073918Z","steps":["trace[1237376808] 'process raft request' (duration: 220.86436ms)","trace[1237376808] 'compare' (duration: 34.437082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:17.0741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.01614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:09:17.074198Z","caller":"traceutil/trace.go:171","msg":"trace[1659179532] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5019; }","duration":"405.153882ms","start":"2026-09-19T06:09:16.669026Z","end":"2026-09-19T06:09:17.074179Z","steps":["trace[1659179532] 'agreement among raft nodes before linearized reading' (duration: 404.993019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.074257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.669018Z","time spent":"405.221673ms","remote":"127.0.0.1:39944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:09:17.074583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.488213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-19T06:09:17.074635Z","caller":"traceutil/trace.go:171","msg":"trace[1997740275] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5019; }","duration":"365.582025ms","start":"2026-09-19T06:09:16.709039Z","end":"2026-09-19T06:09:17.074621Z","steps":["trace[1997740275] 'agreement among raft nodes before linearized reading' (duration: 365.432542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.074679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.709012Z","time spent":"365.655696ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T06:09:17.075316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.027522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T06:09:17.07537Z","caller":"traceutil/trace.go:171","msg":"trace[1331246382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5019; }","duration":"457.106484ms","start":"2026-09-19T06:09:16.61825Z","end":"2026-09-19T06:09:17.075356Z","steps":["trace[1331246382] 'agreement among raft nodes before linearized reading' (duration: 456.965551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.075408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.618235Z","time spent":"457.163084ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T06:09:17.075703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.882249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-19T06:09:17.07576Z","caller":"traceutil/trace.go:171","msg":"trace[1898778591] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5019; }","duration":"481.983581ms","start":"2026-09-19T06:09:16.593763Z","end":"2026-09-19T06:09:17.075746Z","steps":["trace[1898778591] 'agreement among raft nodes before linearized reading' (duration: 480.153334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.075941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:16.593743Z","time spent":"482.184636ms","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207433,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-19T06:09:17.460018Z","caller":"traceutil/trace.go:171","msg":"trace[1526372268] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5216; }","duration":"177.738145ms","start":"2026-09-19T06:09:17.28225Z","end":"2026-09-19T06:09:17.459988Z","steps":["trace[1526372268] 'read index received' (duration: 177.566792ms)","trace[1526372268] 'applied index is now lower than readState.Index' (duration: 169.943µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:17.460089Z","caller":"traceutil/trace.go:171","msg":"trace[1496161488] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"192.388715ms","start":"2026-09-19T06:09:17.267664Z","end":"2026-09-19T06:09:17.460053Z","steps":["trace[1496161488] 'process raft request' (duration: 192.13897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:17.460317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.053322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-19T06:09:17.460401Z","caller":"traceutil/trace.go:171","msg":"trace[2083783598] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5023; }","duration":"178.169854ms","start":"2026-09-19T06:09:17.282211Z","end":"2026-09-19T06:09:17.460381Z","steps":["trace[2083783598] 'agreement among raft nodes before linearized reading' (duration: 177.939829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:17.47197Z","caller":"traceutil/trace.go:171","msg":"trace[1704875781] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"104.196935ms","start":"2026-09-19T06:09:17.36775Z","end":"2026-09-19T06:09:17.471947Z","steps":["trace[1704875781] 'process raft request' (duration: 104.057502ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:23.409202Z","caller":"traceutil/trace.go:171","msg":"trace[378257702] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5327; }","duration":"166.189529ms","start":"2026-09-19T06:09:23.242981Z","end":"2026-09-19T06:09:23.409171Z","steps":["trace[378257702] 'read index received' (duration: 99.799593ms)","trace[378257702] 'applied index is now lower than readState.Index' (duration: 66.388636ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:23.40928Z","caller":"traceutil/trace.go:171","msg":"trace[1394384572] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"169.953367ms","start":"2026-09-19T06:09:23.239292Z","end":"2026-09-19T06:09:23.409246Z","steps":["trace[1394384572] 'process raft request' (duration: 103.52788ms)","trace[1394384572] 'compare' (duration: 65.957967ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:23.40969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.664258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-19T06:09:23.409882Z","caller":"traceutil/trace.go:171","msg":"trace[1511201368] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5131; }","duration":"166.851933ms","start":"2026-09-19T06:09:23.242942Z","end":"2026-09-19T06:09:23.409794Z","steps":["trace[1511201368] 'agreement among raft nodes before linearized reading' (duration: 166.322722ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.173306Z","caller":"traceutil/trace.go:171","msg":"trace[345062410] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"102.419971ms","start":"2026-09-19T06:09:31.070855Z","end":"2026-09-19T06:09:31.173275Z","steps":["trace[345062410] 'process raft request' (duration: 102.235057ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.592893Z","caller":"traceutil/trace.go:171","msg":"trace[1160562834] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"361.049085ms","start":"2026-09-19T06:09:31.231819Z","end":"2026-09-19T06:09:31.592868Z","steps":["trace[1160562834] 'process raft request' (duration: 341.618744ms)","trace[1160562834] 'compare' (duration: 19.035394ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:31.593175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:31.231801Z","time spent":"361.148338ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:31.607122Z","caller":"traceutil/trace.go:171","msg":"trace[1843005191] linearizableReadLoop","detail":"{readStateIndex:5388; appliedIndex:5386; }","duration":"158.06798ms","start":"2026-09-19T06:09:31.449025Z","end":"2026-09-19T06:09:31.607093Z","steps":["trace[1843005191] 'read index received' (duration: 124.425804ms)","trace[1843005191] 'applied index is now lower than readState.Index' (duration: 33.640896ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:31.607328Z","caller":"traceutil/trace.go:171","msg":"trace[78753932] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"170.816277ms","start":"2026-09-19T06:09:31.436477Z","end":"2026-09-19T06:09:31.607294Z","steps":["trace[78753932] 'process raft request' (duration: 170.44429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:31.607348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.299345ms","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-09-19T06:09:31.607398Z","caller":"traceutil/trace.go:171","msg":"trace[27175566] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5190; }","duration":"158.415587ms","start":"2026-09-19T06:09:31.448965Z","end":"2026-09-19T06:09:31.60738Z","steps":["trace[27175566] 'agreement among raft nodes before linearized reading' (duration: 158.242544ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.818483Z","caller":"traceutil/trace.go:171","msg":"trace[553666906] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"128.129188ms","start":"2026-09-19T06:09:31.690333Z","end":"2026-09-19T06:09:31.818462Z","steps":["trace[553666906] 'process raft request' (duration: 127.958645ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.945533Z","caller":"traceutil/trace.go:171","msg":"trace[993959360] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"120.059536ms","start":"2026-09-19T06:09:31.825447Z","end":"2026-09-19T06:09:31.945506Z","steps":["trace[993959360] 'process raft request' (duration: 85.257937ms)","trace[993959360] 'compare' (duration: 34.451982ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:31.94574Z","caller":"traceutil/trace.go:171","msg":"trace[605357027] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"120.27465ms","start":"2026-09-19T06:09:31.825431Z","end":"2026-09-19T06:09:31.945705Z","steps":["trace[605357027] 'process raft request' (duration: 120.030595ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.946031Z","caller":"traceutil/trace.go:171","msg":"trace[54569885] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"120.516695ms","start":"2026-09-19T06:09:31.82546Z","end":"2026-09-19T06:09:31.945977Z","steps":["trace[54569885] 'process raft request' (duration: 120.091006ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:31.946153Z","caller":"traceutil/trace.go:171","msg":"trace[1184743388] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"120.187209ms","start":"2026-09-19T06:09:31.825945Z","end":"2026-09-19T06:09:31.946132Z","steps":["trace[1184743388] 'process raft request' (duration: 119.806491ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:42.702483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2815} {"level":"info","ts":"2026-09-19T06:09:42.829136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2815,"took":"125.646374ms","hash":930440170,"current-db-size-bytes":52277248,"current-db-size":"52 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T06:09:42.829293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":930440170,"revision":2815,"compact-revision":-1} {"level":"info","ts":"2026-09-19T06:09:58.787132Z","caller":"traceutil/trace.go:171","msg":"trace[926581172] linearizableReadLoop","detail":"{readStateIndex:5684; appliedIndex:5683; }","duration":"156.862835ms","start":"2026-09-19T06:09:58.63025Z","end":"2026-09-19T06:09:58.787113Z","steps":["trace[926581172] 'read index received' (duration: 156.678001ms)","trace[926581172] 'applied index is now lower than readState.Index' (duration: 183.904µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:58.787395Z","caller":"traceutil/trace.go:171","msg":"trace[1633842728] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"163.7017ms","start":"2026-09-19T06:09:58.623674Z","end":"2026-09-19T06:09:58.787376Z","steps":["trace[1633842728] 'process raft request' (duration: 163.312462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:58.787674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.406275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T06:09:58.787711Z","caller":"traceutil/trace.go:171","msg":"trace[2139814488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5478; }","duration":"157.474607ms","start":"2026-09-19T06:09:58.630226Z","end":"2026-09-19T06:09:58.7877Z","steps":["trace[2139814488] 'agreement among raft nodes before linearized reading' (duration: 157.347515ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:10:19.477119Z","caller":"traceutil/trace.go:171","msg":"trace[680328001] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"100.067077ms","start":"2026-09-19T06:10:19.37702Z","end":"2026-09-19T06:10:19.477087Z","steps":["trace[680328001] 'process raft request' (duration: 99.896134ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:42.713149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5292} {"level":"info","ts":"2026-09-19T06:14:42.79268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5292,"took":"78.055997ms","hash":2969357736,"current-db-size-bytes":52277248,"current-db-size":"52 MB","current-db-size-in-use-bytes":18640896,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T06:14:42.792747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2969357736,"revision":5292,"compact-revision":2815}