{"level":"warn","ts":"2026-07-15T03:09:39.728718Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T03:09:39.728817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-15T03:09:39.729052Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T03:09:39.729073Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-15T03:09:39.729093Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T03:09:39.729753Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-07-15T03:09:39.729851Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-15T03:09:39.734884Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.773722ms"} {"level":"info","ts":"2026-07-15T03:09:39.744506Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-07-15T03:09:39.744621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T03:09:39.744643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-07-15T03:09:39.744653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T03:09:39.744661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-07-15T03:09:39.744704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-07-15T03:09:39.757121Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T03:09:39.759378Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T03:09:39.761083Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-15T03:09:39.763168Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T03:09:39.763442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T03:09:39.765425Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T03:09:39.765712Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T03:09:39.765729Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T03:09:39.766883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-07-15T03:09:39.766999Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-15T03:09:39.768903Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T03:09:39.769108Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-15T03:09:39.769155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-15T03:09:39.770038Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T03:09:39.770088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T03:09:40.446241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T03:09:40.446292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T03:09:40.446319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-07-15T03:09:40.446333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-07-15T03:09:40.446341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-15T03:09:40.446352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-07-15T03:09:40.446362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-15T03:09:40.44735Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:09:40.447598Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T03:09:40.447661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:09:40.448129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T03:09:40.44811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:09:40.448349Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:09:40.448322Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T03:09:40.448392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T03:09:40.448386Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T03:09:40.453816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T03:09:40.454031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-07-15T03:09:49.297809Z","caller":"traceutil/trace.go:171","msg":"trace[587854684] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"122.448909ms","start":"2026-07-15T03:09:49.175326Z","end":"2026-07-15T03:09:49.297775Z","steps":["trace[587854684] 'process raft request' (duration: 122.276995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:09:49.626949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.520952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:09:49.627319Z","caller":"traceutil/trace.go:171","msg":"trace[1580864918] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"302.076214ms","start":"2026-07-15T03:09:49.325202Z","end":"2026-07-15T03:09:49.627278Z","steps":["trace[1580864918] 'process raft request' (duration: 148.801347ms)","trace[1580864918] 'compare' (duration: 152.393367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:09:49.627374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:09:49.32518Z","time spent":"302.174441ms","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T03:09:49.974562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.315615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:09:49.974689Z","caller":"traceutil/trace.go:171","msg":"trace[1874736913] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"339.655104ms","start":"2026-07-15T03:09:49.635014Z","end":"2026-07-15T03:09:49.974669Z","steps":["trace[1874736913] 'process raft request' (duration: 178.151795ms)","trace[1874736913] 'compare' (duration: 161.134862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:09:49.974975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:09:49.634999Z","time spent":"339.75213ms","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T03:09:50.989019Z","caller":"traceutil/trace.go:171","msg":"trace[113396722] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"114.004013ms","start":"2026-07-15T03:09:50.874993Z","end":"2026-07-15T03:09:50.988997Z","steps":["trace[113396722] 'read index received' (duration: 104.428238ms)","trace[113396722] 'applied index is now lower than readState.Index' (duration: 9.574715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:09:50.989341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.319883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-15T03:09:50.989462Z","caller":"traceutil/trace.go:171","msg":"trace[377839755] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"114.475558ms","start":"2026-07-15T03:09:50.874963Z","end":"2026-07-15T03:09:50.989438Z","steps":["trace[377839755] 'agreement among raft nodes before linearized reading' (duration: 114.255615ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:09:50.98954Z","caller":"traceutil/trace.go:171","msg":"trace[287057628] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"140.236583ms","start":"2026-07-15T03:09:50.849274Z","end":"2026-07-15T03:09:50.989511Z","steps":["trace[287057628] 'process raft request' (duration: 130.28025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:09:51.351197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.691194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:09:51.351652Z","caller":"traceutil/trace.go:171","msg":"trace[1245682247] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"189.049307ms","start":"2026-07-15T03:09:51.162568Z","end":"2026-07-15T03:09:51.351617Z","steps":["trace[1245682247] 'process raft request' (duration: 71.699403ms)","trace[1245682247] 'compare' (duration: 116.48863ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:09:51.351648Z","caller":"traceutil/trace.go:171","msg":"trace[1210756482] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"176.466717ms","start":"2026-07-15T03:09:51.17515Z","end":"2026-07-15T03:09:51.351617Z","steps":["trace[1210756482] 'read index received' (duration: 59.043085ms)","trace[1210756482] 'applied index is now lower than readState.Index' (duration: 117.364114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:09:51.35199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.832554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-15T03:09:51.35203Z","caller":"traceutil/trace.go:171","msg":"trace[1565089904] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:363; }","duration":"176.909862ms","start":"2026-07-15T03:09:51.17511Z","end":"2026-07-15T03:09:51.35202Z","steps":["trace[1565089904] 'agreement among raft nodes before linearized reading' (duration: 176.750357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:09:51.352132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.299592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-07-15T03:09:51.352464Z","caller":"traceutil/trace.go:171","msg":"trace[1939523729] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:363; }","duration":"170.652321ms","start":"2026-07-15T03:09:51.181709Z","end":"2026-07-15T03:09:51.352361Z","steps":["trace[1939523729] 'agreement among raft nodes before linearized reading' (duration: 170.245864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:09:51.352701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.426202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-15T03:09:51.353413Z","caller":"traceutil/trace.go:171","msg":"trace[1007824688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:363; }","duration":"129.202017ms","start":"2026-07-15T03:09:51.224169Z","end":"2026-07-15T03:09:51.353371Z","steps":["trace[1007824688] 'agreement among raft nodes before linearized reading' (duration: 127.806062ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:24.972273Z","caller":"traceutil/trace.go:171","msg":"trace[396017559] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"186.68061ms","start":"2026-07-15T03:10:24.78557Z","end":"2026-07-15T03:10:24.972251Z","steps":["trace[396017559] 'process raft request' (duration: 179.112023ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:24.972388Z","caller":"traceutil/trace.go:171","msg":"trace[1712500884] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"146.43452ms","start":"2026-07-15T03:10:24.825921Z","end":"2026-07-15T03:10:24.972356Z","steps":["trace[1712500884] 'process raft request' (duration: 146.291571ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:24.973208Z","caller":"traceutil/trace.go:171","msg":"trace[1347558428] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"186.76589ms","start":"2026-07-15T03:10:24.786424Z","end":"2026-07-15T03:10:24.97319Z","steps":["trace[1347558428] 'process raft request' (duration: 185.703396ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:27.845576Z","caller":"traceutil/trace.go:171","msg":"trace[631559337] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"363.297638ms","start":"2026-07-15T03:10:27.482249Z","end":"2026-07-15T03:10:27.845546Z","steps":["trace[631559337] 'process raft request' (duration: 363.153548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:27.845787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:10:27.48223Z","time spent":"363.499207ms","remote":"127.0.0.1:47774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T03:10:31.468008Z","caller":"traceutil/trace.go:171","msg":"trace[942293284] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"241.432134ms","start":"2026-07-15T03:10:31.22655Z","end":"2026-07-15T03:10:31.467982Z","steps":["trace[942293284] 'process raft request' (duration: 240.790775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:32.116983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317221521598045,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T03:10:32.419373Z","caller":"traceutil/trace.go:171","msg":"trace[181304825] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"802.980783ms","start":"2026-07-15T03:10:31.61636Z","end":"2026-07-15T03:10:32.419341Z","steps":["trace[181304825] 'read index received' (duration: 775.607714ms)","trace[181304825] 'applied index is now lower than readState.Index' (duration: 27.371689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:10:32.419637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.578035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:10:32.419699Z","caller":"traceutil/trace.go:171","msg":"trace[861231445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"426.676595ms","start":"2026-07-15T03:10:31.993005Z","end":"2026-07-15T03:10:32.419682Z","steps":["trace[861231445] 'agreement among raft nodes before linearized reading' (duration: 426.553305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:32.41975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:10:31.992989Z","time spent":"426.749705ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:10:32.419748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.380102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4g97v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-15T03:10:32.419814Z","caller":"traceutil/trace.go:171","msg":"trace[844161005] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4g97v; range_end:; response_count:1; response_revision:650; }","duration":"803.446142ms","start":"2026-07-15T03:10:31.616352Z","end":"2026-07-15T03:10:32.419798Z","steps":["trace[844161005] 'agreement among raft nodes before linearized reading' (duration: 803.162732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:32.419869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:10:31.616306Z","time spent":"803.549882ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-4g97v\" "} {"level":"warn","ts":"2026-07-15T03:10:32.420056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.390205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-15T03:10:32.420119Z","caller":"traceutil/trace.go:171","msg":"trace[2097600128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"152.491825ms","start":"2026-07-15T03:10:32.267612Z","end":"2026-07-15T03:10:32.420104Z","steps":["trace[2097600128] 'agreement among raft nodes before linearized reading' (duration: 152.346195ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:32.420333Z","caller":"traceutil/trace.go:171","msg":"trace[1008284921] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"886.560501ms","start":"2026-07-15T03:10:31.532844Z","end":"2026-07-15T03:10:32.419405Z","steps":["trace[1008284921] 'process raft request' (duration: 859.144122ms)","trace[1008284921] 'compare' (duration: 27.035989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:10:32.420505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:10:31.532822Z","time spent":"887.577749ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T03:10:34.011723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.359693ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:10:34.011765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.757061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:10:34.011852Z","caller":"traceutil/trace.go:171","msg":"trace[698125533] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:688; }","duration":"114.509923ms","start":"2026-07-15T03:10:33.897323Z","end":"2026-07-15T03:10:34.011833Z","steps":["trace[698125533] 'range keys from in-memory index tree' (duration: 114.346503ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:34.011878Z","caller":"traceutil/trace.go:171","msg":"trace[758831877] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:688; }","duration":"214.929281ms","start":"2026-07-15T03:10:33.796921Z","end":"2026-07-15T03:10:34.011851Z","steps":["trace[758831877] 'range keys from in-memory index tree' (duration: 214.632201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:48.734832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.702656ms","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-07-15T03:10:48.734861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.365818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T03:10:48.734907Z","caller":"traceutil/trace.go:171","msg":"trace[2070140478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"103.451828ms","start":"2026-07-15T03:10:48.631441Z","end":"2026-07-15T03:10:48.734893Z","steps":["trace[2070140478] 'range keys from in-memory index tree' (duration: 103.222588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:48.734902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.720807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:10:48.734906Z","caller":"traceutil/trace.go:171","msg":"trace[816553090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"140.810606ms","start":"2026-07-15T03:10:48.594077Z","end":"2026-07-15T03:10:48.734888Z","steps":["trace[816553090] 'range keys from in-memory index tree' (duration: 140.549465ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.734984Z","caller":"traceutil/trace.go:171","msg":"trace[970622979] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:813; }","duration":"280.844238ms","start":"2026-07-15T03:10:48.454124Z","end":"2026-07-15T03:10:48.734968Z","steps":["trace[970622979] 'count revisions from in-memory index tree' (duration: 280.643347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:10:48.734814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.430835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:10:48.735069Z","caller":"traceutil/trace.go:171","msg":"trace[1081207790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"104.706816ms","start":"2026-07-15T03:10:48.630347Z","end":"2026-07-15T03:10:48.735054Z","steps":["trace[1081207790] 'range keys from in-memory index tree' (duration: 104.282824ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.871091Z","caller":"traceutil/trace.go:171","msg":"trace[887408248] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"144.665862ms","start":"2026-07-15T03:10:48.7264Z","end":"2026-07-15T03:10:48.871066Z","steps":["trace[887408248] 'process raft request' (duration: 144.510871ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.878829Z","caller":"traceutil/trace.go:171","msg":"trace[2118343282] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"139.678399ms","start":"2026-07-15T03:10:48.739127Z","end":"2026-07-15T03:10:48.878806Z","steps":["trace[2118343282] 'process raft request' (duration: 139.466158ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.878891Z","caller":"traceutil/trace.go:171","msg":"trace[921026371] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"139.588829ms","start":"2026-07-15T03:10:48.739293Z","end":"2026-07-15T03:10:48.878882Z","steps":["trace[921026371] 'process raft request' (duration: 139.329887ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.87883Z","caller":"traceutil/trace.go:171","msg":"trace[1925791772] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"139.770879ms","start":"2026-07-15T03:10:48.739047Z","end":"2026-07-15T03:10:48.878818Z","steps":["trace[1925791772] 'process raft request' (duration: 139.444458ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:10:48.878973Z","caller":"traceutil/trace.go:171","msg":"trace[24090136] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"137.516655ms","start":"2026-07-15T03:10:48.741451Z","end":"2026-07-15T03:10:48.878968Z","steps":["trace[24090136] 'process raft request' (duration: 137.288344ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:11:20.045548Z","caller":"traceutil/trace.go:171","msg":"trace[1226550801] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"218.641457ms","start":"2026-07-15T03:11:19.826866Z","end":"2026-07-15T03:11:20.045507Z","steps":["trace[1226550801] 'process raft request' (duration: 218.439584ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:11:20.049003Z","caller":"traceutil/trace.go:171","msg":"trace[313388608] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"110.987641ms","start":"2026-07-15T03:11:19.937976Z","end":"2026-07-15T03:11:20.048964Z","steps":["trace[313388608] 'process raft request' (duration: 110.772747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:11:37.547035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.09103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T03:11:37.547145Z","caller":"traceutil/trace.go:171","msg":"trace[402808117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"103.260213ms","start":"2026-07-15T03:11:37.443865Z","end":"2026-07-15T03:11:37.547125Z","steps":["trace[402808117] 'range keys from in-memory index tree' (duration: 102.854835ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:09.532129Z","caller":"traceutil/trace.go:171","msg":"trace[1454807611] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"196.71151ms","start":"2026-07-15T03:12:09.335388Z","end":"2026-07-15T03:12:09.5321Z","steps":["trace[1454807611] 'process raft request' (duration: 98.918569ms)","trace[1454807611] 'compare' (duration: 97.573386ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:09.532247Z","caller":"traceutil/trace.go:171","msg":"trace[525816038] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"183.859015ms","start":"2026-07-15T03:12:09.348351Z","end":"2026-07-15T03:12:09.53221Z","steps":["trace[525816038] 'read index received' (duration: 85.967602ms)","trace[525816038] 'applied index is now lower than readState.Index' (duration: 97.890213ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:09.532372Z","caller":"traceutil/trace.go:171","msg":"trace[91381249] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"193.839528ms","start":"2026-07-15T03:12:09.338522Z","end":"2026-07-15T03:12:09.532362Z","steps":["trace[91381249] 'process raft request' (duration: 193.52339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:09.532526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.361145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-15T03:12:09.532567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.50922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T03:12:09.5326Z","caller":"traceutil/trace.go:171","msg":"trace[307267240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"119.576502ms","start":"2026-07-15T03:12:09.413013Z","end":"2026-07-15T03:12:09.53259Z","steps":["trace[307267240] 'agreement among raft nodes before linearized reading' (duration: 119.488459ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:09.5326Z","caller":"traceutil/trace.go:171","msg":"trace[1147573947] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"172.481418ms","start":"2026-07-15T03:12:09.360103Z","end":"2026-07-15T03:12:09.532584Z","steps":["trace[1147573947] 'agreement among raft nodes before linearized reading' (duration: 172.298713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:09.532631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.270716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:12:09.532669Z","caller":"traceutil/trace.go:171","msg":"trace[1026861427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1898; }","duration":"184.345428ms","start":"2026-07-15T03:12:09.348313Z","end":"2026-07-15T03:12:09.532659Z","steps":["trace[1026861427] 'agreement among raft nodes before linearized reading' (duration: 184.179924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:10.265708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317221521600536,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T03:12:10.332521Z","caller":"traceutil/trace.go:171","msg":"trace[252107258] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"610.419241ms","start":"2026-07-15T03:12:09.722074Z","end":"2026-07-15T03:12:10.332494Z","steps":["trace[252107258] 'process raft request' (duration: 610.228816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:10.332951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:09.722053Z","time spent":"610.550274ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:10.461269Z","caller":"traceutil/trace.go:171","msg":"trace[125987606] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1974; }","duration":"696.600033ms","start":"2026-07-15T03:12:09.764641Z","end":"2026-07-15T03:12:10.461241Z","steps":["trace[125987606] 'read index received' (duration: 567.64952ms)","trace[125987606] 'applied index is now lower than readState.Index' (duration: 128.949593ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:10.46144Z","caller":"traceutil/trace.go:171","msg":"trace[362658354] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"198.629431ms","start":"2026-07-15T03:12:10.262754Z","end":"2026-07-15T03:12:10.461384Z","steps":["trace[362658354] 'process raft request' (duration: 170.343594ms)","trace[362658354] 'compare' (duration: 28.00563ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:10.461632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.973883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-15T03:12:10.461681Z","caller":"traceutil/trace.go:171","msg":"trace[949255622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6; range_end:; response_count:1; response_revision:1902; }","duration":"697.074745ms","start":"2026-07-15T03:12:09.764593Z","end":"2026-07-15T03:12:10.461668Z","steps":["trace[949255622] 'agreement among raft nodes before linearized reading' (duration: 696.793828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:10.461724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:09.764575Z","time spent":"697.139196ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6\" "} {"level":"warn","ts":"2026-07-15T03:12:10.461791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.835811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-15T03:12:10.461657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.55859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:12:10.461839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.227302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-15T03:12:10.461853Z","caller":"traceutil/trace.go:171","msg":"trace[191096400] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"132.945864ms","start":"2026-07-15T03:12:10.328893Z","end":"2026-07-15T03:12:10.461839Z","steps":["trace[191096400] 'agreement among raft nodes before linearized reading' (duration: 132.731358ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:10.461863Z","caller":"traceutil/trace.go:171","msg":"trace[899907696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"468.818427ms","start":"2026-07-15T03:12:09.993029Z","end":"2026-07-15T03:12:10.461847Z","steps":["trace[899907696] 'agreement among raft nodes before linearized reading' (duration: 468.5531ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:10.461892Z","caller":"traceutil/trace.go:171","msg":"trace[557127822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6; range_end:; response_count:1; response_revision:1902; }","duration":"360.306745ms","start":"2026-07-15T03:12:10.101572Z","end":"2026-07-15T03:12:10.461878Z","steps":["trace[557127822] 'agreement among raft nodes before linearized reading' (duration: 360.11604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:10.461921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:09.992958Z","time spent":"468.95361ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:12:10.461934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:10.101559Z","time spent":"360.366966ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4644754-d9b4-40a3-8b34-7676737bc0f6\" "} {"level":"info","ts":"2026-07-15T03:12:10.671336Z","caller":"traceutil/trace.go:171","msg":"trace[43638858] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"131.659912ms","start":"2026-07-15T03:12:10.539629Z","end":"2026-07-15T03:12:10.671289Z","steps":["trace[43638858] 'read index received' (duration: 117.235166ms)","trace[43638858] 'applied index is now lower than readState.Index' (duration: 14.423366ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:10.67144Z","caller":"traceutil/trace.go:171","msg":"trace[1977336605] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"200.713855ms","start":"2026-07-15T03:12:10.470682Z","end":"2026-07-15T03:12:10.671396Z","steps":["trace[1977336605] 'process raft request' (duration: 186.156845ms)","trace[1977336605] 'compare' (duration: 14.265262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:10.671739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.072932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T03:12:10.671849Z","caller":"traceutil/trace.go:171","msg":"trace[606297055] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1904; }","duration":"132.264766ms","start":"2026-07-15T03:12:10.539563Z","end":"2026-07-15T03:12:10.671828Z","steps":["trace[606297055] 'agreement among raft nodes before linearized reading' (duration: 131.932878ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:11.149632Z","caller":"traceutil/trace.go:171","msg":"trace[450240570] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"155.952765ms","start":"2026-07-15T03:12:10.993662Z","end":"2026-07-15T03:12:11.149615Z","steps":["trace[450240570] 'read index received' (duration: 155.924894ms)","trace[450240570] 'applied index is now lower than readState.Index' (duration: 27.011µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:11.149728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.059197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:12:11.149745Z","caller":"traceutil/trace.go:171","msg":"trace[467736665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"156.130169ms","start":"2026-07-15T03:12:10.99361Z","end":"2026-07-15T03:12:11.14974Z","steps":["trace[467736665] 'agreement among raft nodes before linearized reading' (duration: 156.081748ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:11.149872Z","caller":"traceutil/trace.go:171","msg":"trace[848256054] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"322.658776ms","start":"2026-07-15T03:12:10.827193Z","end":"2026-07-15T03:12:11.149852Z","steps":["trace[848256054] 'process raft request' (duration: 322.325307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:11.14998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:10.827164Z","time spent":"322.754357ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:11.173874Z","caller":"traceutil/trace.go:171","msg":"trace[105651388] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"102.988465ms","start":"2026-07-15T03:12:11.07087Z","end":"2026-07-15T03:12:11.173858Z","steps":["trace[105651388] 'process raft request' (duration: 102.867452ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:28.814803Z","caller":"traceutil/trace.go:171","msg":"trace[752226713] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"235.187668ms","start":"2026-07-15T03:12:28.579591Z","end":"2026-07-15T03:12:28.814779Z","steps":["trace[752226713] 'read index received' (duration: 234.976362ms)","trace[752226713] 'applied index is now lower than readState.Index' (duration: 210.116µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:28.814842Z","caller":"traceutil/trace.go:171","msg":"trace[2057754406] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"238.815918ms","start":"2026-07-15T03:12:28.575993Z","end":"2026-07-15T03:12:28.814809Z","steps":["trace[2057754406] 'process raft request' (duration: 238.605862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:28.815058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.441974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T03:12:28.81514Z","caller":"traceutil/trace.go:171","msg":"trace[1822310265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2053; }","duration":"235.569988ms","start":"2026-07-15T03:12:28.579557Z","end":"2026-07-15T03:12:28.815127Z","steps":["trace[1822310265] 'agreement among raft nodes before linearized reading' (duration: 235.324941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:28.815166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.317801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T03:12:28.815253Z","caller":"traceutil/trace.go:171","msg":"trace[1917496944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2053; }","duration":"214.451895ms","start":"2026-07-15T03:12:28.600781Z","end":"2026-07-15T03:12:28.815232Z","steps":["trace[1917496944] 'agreement among raft nodes before linearized reading' (duration: 214.191458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.453864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.545796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:12:29.454009Z","caller":"traceutil/trace.go:171","msg":"trace[536644024] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"634.43881ms","start":"2026-07-15T03:12:28.819548Z","end":"2026-07-15T03:12:29.453987Z","steps":["trace[536644024] 'process raft request' (duration: 528.680568ms)","trace[536644024] 'compare' (duration: 105.349371ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:29.454083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:28.819521Z","time spent":"634.525012ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:29.459164Z","caller":"traceutil/trace.go:171","msg":"trace[1553233164] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"638.644615ms","start":"2026-07-15T03:12:28.820494Z","end":"2026-07-15T03:12:29.459139Z","steps":["trace[1553233164] 'process raft request' (duration: 638.505821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.459297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:28.820478Z","time spent":"638.748027ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:29.467471Z","caller":"traceutil/trace.go:171","msg":"trace[262130532] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2131; }","duration":"560.444861ms","start":"2026-07-15T03:12:28.907013Z","end":"2026-07-15T03:12:29.467457Z","steps":["trace[262130532] 'read index received' (duration: 441.229243ms)","trace[262130532] 'applied index is now lower than readState.Index' (duration: 119.214858ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:29.467481Z","caller":"traceutil/trace.go:171","msg":"trace[2046619982] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"234.115258ms","start":"2026-07-15T03:12:29.233349Z","end":"2026-07-15T03:12:29.467465Z","steps":["trace[2046619982] 'process raft request' (duration: 233.992295ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:29.467547Z","caller":"traceutil/trace.go:171","msg":"trace[1069232007] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"229.189824ms","start":"2026-07-15T03:12:29.238338Z","end":"2026-07-15T03:12:29.467528Z","steps":["trace[1069232007] 'process raft request' (duration: 229.076301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.467627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.526531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:12:29.467665Z","caller":"traceutil/trace.go:171","msg":"trace[1195713302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2057; }","duration":"473.601142ms","start":"2026-07-15T03:12:28.994053Z","end":"2026-07-15T03:12:29.467654Z","steps":["trace[1195713302] 'agreement among raft nodes before linearized reading' (duration: 473.554901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.467693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:28.994037Z","time spent":"473.650044ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:12:29.467783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.157166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-15T03:12:29.467774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.297908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T03:12:29.46758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.564654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:12:29.467829Z","caller":"traceutil/trace.go:171","msg":"trace[959247116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2057; }","duration":"428.239428ms","start":"2026-07-15T03:12:29.039577Z","end":"2026-07-15T03:12:29.467817Z","steps":["trace[959247116] 'agreement among raft nodes before linearized reading' (duration: 428.095534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.467863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:29.039556Z","time spent":"428.299539ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-15T03:12:29.46787Z","caller":"traceutil/trace.go:171","msg":"trace[1782108112] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2057; }","duration":"370.438522ms","start":"2026-07-15T03:12:29.097386Z","end":"2026-07-15T03:12:29.467825Z","steps":["trace[1782108112] 'agreement among raft nodes before linearized reading' (duration: 370.275318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.467964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:29.097366Z","time spent":"370.584456ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-15T03:12:29.467891Z","caller":"traceutil/trace.go:171","msg":"trace[1161207265] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2057; }","duration":"560.882353ms","start":"2026-07-15T03:12:28.90696Z","end":"2026-07-15T03:12:29.467842Z","steps":["trace[1161207265] 'agreement among raft nodes before linearized reading' (duration: 560.579824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:29.468113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:28.906937Z","time spent":"561.16227ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-07-15T03:12:40.992344Z","caller":"traceutil/trace.go:171","msg":"trace[1028020455] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"113.413577ms","start":"2026-07-15T03:12:40.878912Z","end":"2026-07-15T03:12:40.992326Z","steps":["trace[1028020455] 'process raft request' (duration: 113.300143ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:47.707613Z","caller":"traceutil/trace.go:171","msg":"trace[2063492798] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"130.535769ms","start":"2026-07-15T03:12:47.577052Z","end":"2026-07-15T03:12:47.707588Z","steps":["trace[2063492798] 'process raft request' (duration: 130.390815ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:47.715816Z","caller":"traceutil/trace.go:171","msg":"trace[132706499] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"130.456016ms","start":"2026-07-15T03:12:47.585342Z","end":"2026-07-15T03:12:47.715798Z","steps":["trace[132706499] 'process raft request' (duration: 130.338213ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:47.715959Z","caller":"traceutil/trace.go:171","msg":"trace[2108403464] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2242; }","duration":"124.534818ms","start":"2026-07-15T03:12:47.591415Z","end":"2026-07-15T03:12:47.715949Z","steps":["trace[2108403464] 'read index received' (duration: 115.976893ms)","trace[2108403464] 'applied index is now lower than readState.Index' (duration: 8.556785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:47.716215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.237741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T03:12:47.716271Z","caller":"traceutil/trace.go:171","msg":"trace[1087479669] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"116.354464ms","start":"2026-07-15T03:12:47.5999Z","end":"2026-07-15T03:12:47.716254Z","steps":["trace[1087479669] 'agreement among raft nodes before linearized reading' (duration: 116.166789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:47.716372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.926458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T03:12:47.716444Z","caller":"traceutil/trace.go:171","msg":"trace[1506032830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2163; }","duration":"125.046312ms","start":"2026-07-15T03:12:47.59138Z","end":"2026-07-15T03:12:47.716426Z","steps":["trace[1506032830] 'agreement among raft nodes before linearized reading' (duration: 124.829376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:47.971892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.54828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:12:47.972061Z","caller":"traceutil/trace.go:171","msg":"trace[813076452] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"252.120421ms","start":"2026-07-15T03:12:47.719912Z","end":"2026-07-15T03:12:47.972032Z","steps":["trace[813076452] 'process raft request' (duration: 94.366255ms)","trace[813076452] 'compare' (duration: 157.411946ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:47.972095Z","caller":"traceutil/trace.go:171","msg":"trace[1210098571] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"251.552916ms","start":"2026-07-15T03:12:47.720509Z","end":"2026-07-15T03:12:47.972061Z","steps":["trace[1210098571] 'process raft request' (duration: 251.465563ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:47.972094Z","caller":"traceutil/trace.go:171","msg":"trace[791890944] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"249.98038ms","start":"2026-07-15T03:12:47.722096Z","end":"2026-07-15T03:12:47.972077Z","steps":["trace[791890944] 'process raft request' (duration: 249.910068ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:47.972361Z","caller":"traceutil/trace.go:171","msg":"trace[351617354] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2245; }","duration":"170.984264ms","start":"2026-07-15T03:12:47.801046Z","end":"2026-07-15T03:12:47.97203Z","steps":["trace[351617354] 'read index received' (duration: 13.235378ms)","trace[351617354] 'applied index is now lower than readState.Index' (duration: 157.748176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:47.97248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.439086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:12:47.972503Z","caller":"traceutil/trace.go:171","msg":"trace[140954934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2166; }","duration":"171.500968ms","start":"2026-07-15T03:12:47.800996Z","end":"2026-07-15T03:12:47.972497Z","steps":["trace[140954934] 'agreement among raft nodes before linearized reading' (duration: 171.391925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:48.451491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.40599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-07-15T03:12:48.451576Z","caller":"traceutil/trace.go:171","msg":"trace[30255412] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2167; }","duration":"307.523963ms","start":"2026-07-15T03:12:48.144028Z","end":"2026-07-15T03:12:48.451552Z","steps":["trace[30255412] 'range keys from in-memory index tree' (duration: 307.207584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:48.451627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:48.144011Z","time spent":"307.607125ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-15T03:12:48.452114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.425668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-15T03:12:48.452264Z","caller":"traceutil/trace.go:171","msg":"trace[1769753180] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2167; }","duration":"149.620814ms","start":"2026-07-15T03:12:48.30262Z","end":"2026-07-15T03:12:48.452241Z","steps":["trace[1769753180] 'range keys from in-memory index tree' (duration: 149.201571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:48.451428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.562449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c258004b0d163a\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-15T03:12:48.452772Z","caller":"traceutil/trace.go:171","msg":"trace[879888725] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c258004b0d163a; range_end:; response_count:1; response_revision:2167; }","duration":"217.953669ms","start":"2026-07-15T03:12:48.234798Z","end":"2026-07-15T03:12:48.452752Z","steps":["trace[879888725] 'range keys from in-memory index tree' (duration: 216.357574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:49.133841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.487677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:12:49.133852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.655834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:12:49.133924Z","caller":"traceutil/trace.go:171","msg":"trace[399739197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"140.771948ms","start":"2026-07-15T03:12:48.993136Z","end":"2026-07-15T03:12:49.133908Z","steps":["trace[399739197] 'range keys from in-memory index tree' (duration: 140.538681ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:49.133931Z","caller":"traceutil/trace.go:171","msg":"trace[1809589984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"317.63557ms","start":"2026-07-15T03:12:48.816266Z","end":"2026-07-15T03:12:49.133901Z","steps":["trace[1809589984] 'range keys from in-memory index tree' (duration: 317.392973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:49.133993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:48.816247Z","time spent":"317.725143ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T03:12:49.593209Z","caller":"traceutil/trace.go:171","msg":"trace[1994650784] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"551.667667ms","start":"2026-07-15T03:12:49.041517Z","end":"2026-07-15T03:12:49.593185Z","steps":["trace[1994650784] 'process raft request' (duration: 551.525082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:49.593468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.041487Z","time spent":"551.79022ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:12:50.101004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.680132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-15T03:12:50.101312Z","caller":"traceutil/trace.go:171","msg":"trace[1714030416] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2253; }","duration":"528.273831ms","start":"2026-07-15T03:12:49.573007Z","end":"2026-07-15T03:12:50.10128Z","steps":["trace[1714030416] 'read index received' (duration: 20.096126ms)","trace[1714030416] 'applied index is now lower than readState.Index' (duration: 508.175005ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:50.101384Z","caller":"traceutil/trace.go:171","msg":"trace[800840510] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"379.513673ms","start":"2026-07-15T03:12:49.72185Z","end":"2026-07-15T03:12:50.101364Z","steps":["trace[800840510] 'process raft request' (duration: 379.455032ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:50.101504Z","caller":"traceutil/trace.go:171","msg":"trace[17645206] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"389.002767ms","start":"2026-07-15T03:12:49.71248Z","end":"2026-07-15T03:12:50.101483Z","steps":["trace[17645206] 'process raft request' (duration: 388.629486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.101704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.712455Z","time spent":"389.174761ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:12:50.101521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.721836Z","time spent":"379.598106ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:50.101572Z","caller":"traceutil/trace.go:171","msg":"trace[831952113] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"380.467711ms","start":"2026-07-15T03:12:49.72109Z","end":"2026-07-15T03:12:50.101557Z","steps":["trace[831952113] 'process raft request' (duration: 380.167862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.10157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.552879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-15T03:12:50.10198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.721054Z","time spent":"380.861823ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:50.102006Z","caller":"traceutil/trace.go:171","msg":"trace[771649207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2174; }","duration":"529.016172ms","start":"2026-07-15T03:12:49.572955Z","end":"2026-07-15T03:12:50.101971Z","steps":["trace[771649207] 'agreement among raft nodes before linearized reading' (duration: 528.445156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.102051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.572933Z","time spent":"529.104184ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T03:12:50.291027Z","caller":"traceutil/trace.go:171","msg":"trace[1493306299] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"557.130862ms","start":"2026-07-15T03:12:49.733869Z","end":"2026-07-15T03:12:50.291Z","steps":["trace[1493306299] 'process raft request' (duration: 540.606226ms)","trace[1493306299] 'compare' (duration: 16.33421ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:12:50.291269Z","caller":"traceutil/trace.go:171","msg":"trace[34319558] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"182.830226ms","start":"2026-07-15T03:12:50.108414Z","end":"2026-07-15T03:12:50.291244Z","steps":["trace[34319558] 'process raft request' (duration: 182.507676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.291278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.733758Z","time spent":"557.404739ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8559,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:12:50.291059Z","caller":"traceutil/trace.go:171","msg":"trace[1214114364] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"189.644043ms","start":"2026-07-15T03:12:50.101372Z","end":"2026-07-15T03:12:50.291016Z","steps":["trace[1214114364] 'read index received' (duration: 173.063466ms)","trace[1214114364] 'applied index is now lower than readState.Index' (duration: 16.577527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:12:50.291674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.604501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T03:12:50.291758Z","caller":"traceutil/trace.go:171","msg":"trace[2078427338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2176; }","duration":"315.714875ms","start":"2026-07-15T03:12:49.976025Z","end":"2026-07-15T03:12:50.291739Z","steps":["trace[2078427338] 'agreement among raft nodes before linearized reading' (duration: 315.448207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.291757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.724957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:12:50.291806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.976014Z","time spent":"315.783246ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T03:12:50.291831Z","caller":"traceutil/trace.go:171","msg":"trace[1805078741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"298.81286ms","start":"2026-07-15T03:12:49.992995Z","end":"2026-07-15T03:12:50.291808Z","steps":["trace[1805078741] 'agreement among raft nodes before linearized reading' (duration: 298.739047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.291887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.349588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T03:12:50.29198Z","caller":"traceutil/trace.go:171","msg":"trace[1540995053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2176; }","duration":"557.476731ms","start":"2026-07-15T03:12:49.734457Z","end":"2026-07-15T03:12:50.291934Z","steps":["trace[1540995053] 'agreement among raft nodes before linearized reading' (duration: 556.646938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.29199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.90501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-15T03:12:50.292021Z","caller":"traceutil/trace.go:171","msg":"trace[199783703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"315.949891ms","start":"2026-07-15T03:12:49.976064Z","end":"2026-07-15T03:12:50.292014Z","steps":["trace[199783703] 'agreement among raft nodes before linearized reading' (duration: 315.856039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.292043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.976059Z","time spent":"315.978722ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T03:12:50.292052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.734438Z","time spent":"557.577274ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T03:12:50.292068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.414326ms","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-07-15T03:12:50.291685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.48768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:12:50.292123Z","caller":"traceutil/trace.go:171","msg":"trace[681084586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"299.981444ms","start":"2026-07-15T03:12:49.992135Z","end":"2026-07-15T03:12:50.292116Z","steps":["trace[681084586] 'agreement among raft nodes before linearized reading' (duration: 299.316785ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:12:50.292121Z","caller":"traceutil/trace.go:171","msg":"trace[899304457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2176; }","duration":"316.506528ms","start":"2026-07-15T03:12:49.9756Z","end":"2026-07-15T03:12:50.292107Z","steps":["trace[899304457] 'agreement among raft nodes before linearized reading' (duration: 316.366944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:12:50.292143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.992119Z","time spent":"300.018585ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T03:12:50.292177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:12:49.975582Z","time spent":"316.58333ms","remote":"127.0.0.1:47934","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-07-15T03:12:50.292311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.864292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:12:50.293273Z","caller":"traceutil/trace.go:171","msg":"trace[2065413498] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2176; }","duration":"228.411487ms","start":"2026-07-15T03:12:50.064812Z","end":"2026-07-15T03:12:50.293223Z","steps":["trace[2065413498] 'agreement among raft nodes before linearized reading' (duration: 226.873052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.173269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.856993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:14:35.173419Z","caller":"traceutil/trace.go:171","msg":"trace[2112716517] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"664.813551ms","start":"2026-07-15T03:14:34.508582Z","end":"2026-07-15T03:14:35.173395Z","steps":["trace[2112716517] 'process raft request' (duration: 221.723894ms)","trace[2112716517] 'compare' (duration: 441.82611ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:14:35.173493Z","caller":"traceutil/trace.go:171","msg":"trace[2104933212] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"664.062302ms","start":"2026-07-15T03:14:34.509417Z","end":"2026-07-15T03:14:35.173479Z","steps":["trace[2104933212] 'process raft request' (duration: 663.97662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.173512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.508568Z","time spent":"664.900592ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:14:35.17352Z","caller":"traceutil/trace.go:171","msg":"trace[1502783121] linearizableReadLoop","detail":"{readStateIndex:2931; appliedIndex:2930; }","duration":"664.561619ms","start":"2026-07-15T03:14:34.508929Z","end":"2026-07-15T03:14:35.17349Z","steps":["trace[1502783121] 'read index received' (duration: 221.392591ms)","trace[1502783121] 'applied index is now lower than readState.Index' (duration: 443.166438ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:14:35.173558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.5094Z","time spent":"664.123033ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:14:35.173747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.766156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T03:14:35.173783Z","caller":"traceutil/trace.go:171","msg":"trace[1736752353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2825; }","duration":"350.816336ms","start":"2026-07-15T03:14:34.822956Z","end":"2026-07-15T03:14:35.173772Z","steps":["trace[1736752353] 'agreement among raft nodes before linearized reading' (duration: 350.692235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.173791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.84267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-15T03:14:35.173813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.82295Z","time spent":"350.856167ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T03:14:35.173831Z","caller":"traceutil/trace.go:171","msg":"trace[1814383361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2825; }","duration":"354.932451ms","start":"2026-07-15T03:14:34.818888Z","end":"2026-07-15T03:14:35.17382Z","steps":["trace[1814383361] 'agreement among raft nodes before linearized reading' (duration: 354.756919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.173815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.882923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"warn","ts":"2026-07-15T03:14:35.173866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.818869Z","time spent":"354.989313ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T03:14:35.173898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.728571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:14:35.173895Z","caller":"traceutil/trace.go:171","msg":"trace[1113448692] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2825; }","duration":"664.965674ms","start":"2026-07-15T03:14:34.508914Z","end":"2026-07-15T03:14:35.173879Z","steps":["trace[1113448692] 'agreement among raft nodes before linearized reading' (duration: 664.6868ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:14:35.17395Z","caller":"traceutil/trace.go:171","msg":"trace[490125784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2825; }","duration":"180.789161ms","start":"2026-07-15T03:14:34.993134Z","end":"2026-07-15T03:14:35.173923Z","steps":["trace[490125784] 'agreement among raft nodes before linearized reading' (duration: 180.743981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.173961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.508861Z","time spent":"665.089376ms","remote":"127.0.0.1:41234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7486,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-15T03:14:35.174023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.150721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T03:14:35.174089Z","caller":"traceutil/trace.go:171","msg":"trace[1546183081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2825; }","duration":"351.236763ms","start":"2026-07-15T03:14:34.822841Z","end":"2026-07-15T03:14:35.174077Z","steps":["trace[1546183081] 'agreement among raft nodes before linearized reading' (duration: 351.121941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.174124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.822829Z","time spent":"351.287373ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T03:14:35.174236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.697832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-15T03:14:35.174261Z","caller":"traceutil/trace.go:171","msg":"trace[1650127451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2825; }","duration":"352.746812ms","start":"2026-07-15T03:14:34.821508Z","end":"2026-07-15T03:14:35.174254Z","steps":["trace[1650127451] 'agreement among raft nodes before linearized reading' (duration: 352.673581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.174285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.821493Z","time spent":"352.786412ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T03:14:35.174388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.210566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:14:35.174841Z","caller":"traceutil/trace.go:171","msg":"trace[423295452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2825; }","duration":"355.701422ms","start":"2026-07-15T03:14:34.819123Z","end":"2026-07-15T03:14:35.174824Z","steps":["trace[423295452] 'agreement among raft nodes before linearized reading' (duration: 355.186665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:14:35.174882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:14:34.8191Z","time spent":"355.769073ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T03:15:07.41326Z","caller":"traceutil/trace.go:171","msg":"trace[101282102] transaction","detail":"{read_only:false; response_revision:3001; number_of_response:1; }","duration":"116.670347ms","start":"2026-07-15T03:15:07.296566Z","end":"2026-07-15T03:15:07.413237Z","steps":["trace[101282102] 'process raft request' (duration: 116.528244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.747602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.515305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:15:07.74779Z","caller":"traceutil/trace.go:171","msg":"trace[2072098653] linearizableReadLoop","detail":"{readStateIndex:3115; appliedIndex:3113; }","duration":"410.297457ms","start":"2026-07-15T03:15:07.337432Z","end":"2026-07-15T03:15:07.74773Z","steps":["trace[2072098653] 'read index received' (duration: 75.715089ms)","trace[2072098653] 'applied index is now lower than readState.Index' (duration: 334.581178ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:15:07.748079Z","caller":"traceutil/trace.go:171","msg":"trace[1028805844] transaction","detail":"{read_only:false; response_revision:3002; number_of_response:1; }","duration":"449.73344ms","start":"2026-07-15T03:15:07.298334Z","end":"2026-07-15T03:15:07.748067Z","steps":["trace[1028805844] 'process raft request' (duration: 236.679195ms)","trace[1028805844] 'compare' (duration: 212.149948ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:15:07.748511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.298295Z","time spent":"450.191027ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:15:07.748267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.793569ms","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-07-15T03:15:07.748857Z","caller":"traceutil/trace.go:171","msg":"trace[1077327387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3002; }","duration":"410.380008ms","start":"2026-07-15T03:15:07.338441Z","end":"2026-07-15T03:15:07.748821Z","steps":["trace[1077327387] 'agreement among raft nodes before linearized reading' (duration: 409.742088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.748967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.338426Z","time spent":"410.530231ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T03:15:07.748316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.887667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:15:07.749251Z","caller":"traceutil/trace.go:171","msg":"trace[998350908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3002; }","duration":"411.897884ms","start":"2026-07-15T03:15:07.337342Z","end":"2026-07-15T03:15:07.74924Z","steps":["trace[998350908] 'agreement among raft nodes before linearized reading' (duration: 410.941528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.749331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.337317Z","time spent":"412.004486ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T03:15:07.748369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.551024ms","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-07-15T03:15:07.749659Z","caller":"traceutil/trace.go:171","msg":"trace[614130259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3002; }","duration":"373.865366ms","start":"2026-07-15T03:15:07.375786Z","end":"2026-07-15T03:15:07.749652Z","steps":["trace[614130259] 'agreement among raft nodes before linearized reading' (duration: 372.540384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.749769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.375768Z","time spent":"373.991338ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T03:15:07.748407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.24662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T03:15:07.749986Z","caller":"traceutil/trace.go:171","msg":"trace[764354396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3002; }","duration":"376.850578ms","start":"2026-07-15T03:15:07.373127Z","end":"2026-07-15T03:15:07.749978Z","steps":["trace[764354396] 'agreement among raft nodes before linearized reading' (duration: 375.250891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.750096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.373112Z","time spent":"376.945089ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T03:15:07.748446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.638061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-15T03:15:07.750333Z","caller":"traceutil/trace.go:171","msg":"trace[1851605040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3002; }","duration":"383.561445ms","start":"2026-07-15T03:15:07.366759Z","end":"2026-07-15T03:15:07.750321Z","steps":["trace[1851605040] 'agreement among raft nodes before linearized reading' (duration: 381.656271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:07.750488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.366733Z","time spent":"383.744277ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T03:15:08.349988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.455791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:15:08.350243Z","caller":"traceutil/trace.go:171","msg":"trace[452152487] transaction","detail":"{read_only:false; response_revision:3008; number_of_response:1; }","duration":"182.643058ms","start":"2026-07-15T03:15:08.167583Z","end":"2026-07-15T03:15:08.350226Z","steps":["trace[452152487] 'process raft request' (duration: 182.604747ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:15:08.35055Z","caller":"traceutil/trace.go:171","msg":"trace[635821806] transaction","detail":"{read_only:false; response_revision:3007; number_of_response:1; }","duration":"594.875794ms","start":"2026-07-15T03:15:07.755664Z","end":"2026-07-15T03:15:08.35054Z","steps":["trace[635821806] 'process raft request' (duration: 594.485238ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:15:08.350588Z","caller":"traceutil/trace.go:171","msg":"trace[1762812861] transaction","detail":"{read_only:false; response_revision:3004; number_of_response:1; }","duration":"595.19387ms","start":"2026-07-15T03:15:07.755384Z","end":"2026-07-15T03:15:08.350578Z","steps":["trace[1762812861] 'process raft request' (duration: 153.080269ms)","trace[1762812861] 'compare' (duration: 441.180134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:15:08.350623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.755658Z","time spent":"594.928305ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:15:08.350782Z","caller":"traceutil/trace.go:171","msg":"trace[695922894] linearizableReadLoop","detail":"{readStateIndex:3120; appliedIndex:3116; }","duration":"558.420902ms","start":"2026-07-15T03:15:07.792351Z","end":"2026-07-15T03:15:08.350772Z","steps":["trace[695922894] 'read index received' (duration: 116.165838ms)","trace[695922894] 'applied index is now lower than readState.Index' (duration: 442.254014ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:15:08.350527Z","caller":"traceutil/trace.go:171","msg":"trace[1348793276] transaction","detail":"{read_only:false; response_revision:3006; number_of_response:1; }","duration":"595.004137ms","start":"2026-07-15T03:15:07.75549Z","end":"2026-07-15T03:15:08.350494Z","steps":["trace[1348793276] 'process raft request' (duration: 594.62786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:08.350899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.755462Z","time spent":"595.406013ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:15:08.351045Z","caller":"traceutil/trace.go:171","msg":"trace[1511823879] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"595.544976ms","start":"2026-07-15T03:15:07.755486Z","end":"2026-07-15T03:15:08.351031Z","steps":["trace[1511823879] 'process raft request' (duration: 594.586779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:08.351162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.755478Z","time spent":"595.605437ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:15:08.351236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.88072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c2581de3bc8795\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-15T03:15:08.351287Z","caller":"traceutil/trace.go:171","msg":"trace[1735633463] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c2581de3bc8795; range_end:; response_count:1; response_revision:3008; }","duration":"558.957473ms","start":"2026-07-15T03:15:07.792302Z","end":"2026-07-15T03:15:08.35126Z","steps":["trace[1735633463] 'agreement among raft nodes before linearized reading' (duration: 558.843571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:08.351326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.792278Z","time spent":"559.040835ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c2581de3bc8795\" "} {"level":"warn","ts":"2026-07-15T03:15:08.35135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.867734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:15:08.351374Z","caller":"traceutil/trace.go:171","msg":"trace[1211621914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3008; }","duration":"357.929205ms","start":"2026-07-15T03:15:07.993439Z","end":"2026-07-15T03:15:08.351369Z","steps":["trace[1211621914] 'agreement among raft nodes before linearized reading' (duration: 357.889415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:15:08.35139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.99342Z","time spent":"357.967826ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T03:15:08.351641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:15:07.755366Z","time spent":"595.480855ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:15:13.869261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.442206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-15T03:15:13.869338Z","caller":"traceutil/trace.go:171","msg":"trace[938136301] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:3035; }","duration":"241.549458ms","start":"2026-07-15T03:15:13.627772Z","end":"2026-07-15T03:15:13.869321Z","steps":["trace[938136301] 'range keys from in-memory index tree' (duration: 241.250304ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:15:13.869624Z","caller":"traceutil/trace.go:171","msg":"trace[1965304659] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"101.84384ms","start":"2026-07-15T03:15:13.76775Z","end":"2026-07-15T03:15:13.869594Z","steps":["trace[1965304659] 'process raft request' (duration: 51.075653ms)","trace[1965304659] 'compare' (duration: 50.425051ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:16:40.828941Z","caller":"traceutil/trace.go:171","msg":"trace[347846159] transaction","detail":"{read_only:false; response_revision:3486; number_of_response:1; }","duration":"140.244506ms","start":"2026-07-15T03:16:40.68867Z","end":"2026-07-15T03:16:40.828915Z","steps":["trace[347846159] 'process raft request' (duration: 139.662812ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:16:41.6533Z","caller":"traceutil/trace.go:171","msg":"trace[76900534] transaction","detail":"{read_only:false; response_revision:3493; number_of_response:1; }","duration":"191.15347ms","start":"2026-07-15T03:16:41.46213Z","end":"2026-07-15T03:16:41.653283Z","steps":["trace[76900534] 'process raft request' (duration: 191.046088ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:16:41.654101Z","caller":"traceutil/trace.go:171","msg":"trace[185687200] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"134.628909ms","start":"2026-07-15T03:16:41.519453Z","end":"2026-07-15T03:16:41.654081Z","steps":["trace[185687200] 'process raft request' (duration: 134.514126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:55.057324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.827125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:499028"} {"level":"info","ts":"2026-07-15T03:18:55.057415Z","caller":"traceutil/trace.go:171","msg":"trace[1069548293] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4819; }","duration":"203.956948ms","start":"2026-07-15T03:18:54.853437Z","end":"2026-07-15T03:18:55.057394Z","steps":["trace[1069548293] 'range keys from in-memory index tree' (duration: 203.459524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:55.057682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.963842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-498fb692-5f89-49a9-a7d4-e91a34cc127d\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-15T03:18:55.058405Z","caller":"traceutil/trace.go:171","msg":"trace[1560178047] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-498fb692-5f89-49a9-a7d4-e91a34cc127d; range_end:; response_count:1; response_revision:4819; }","duration":"202.100397ms","start":"2026-07-15T03:18:54.85565Z","end":"2026-07-15T03:18:55.05775Z","steps":["trace[1560178047] 'range keys from in-memory index tree' (duration: 201.839049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:55.060632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.47583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-07-15T03:18:55.060697Z","caller":"traceutil/trace.go:171","msg":"trace[999791659] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4819; }","duration":"113.568902ms","start":"2026-07-15T03:18:54.947112Z","end":"2026-07-15T03:18:55.060681Z","steps":["trace[999791659] 'range keys from in-memory index tree' (duration: 109.703825ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:56.438676Z","caller":"traceutil/trace.go:171","msg":"trace[1642080869] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5017; }","duration":"127.631838ms","start":"2026-07-15T03:18:56.311025Z","end":"2026-07-15T03:18:56.438656Z","steps":["trace[1642080869] 'read index received' (duration: 127.462213ms)","trace[1642080869] 'applied index is now lower than readState.Index' (duration: 168.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:56.438751Z","caller":"traceutil/trace.go:171","msg":"trace[1086086409] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"149.703152ms","start":"2026-07-15T03:18:56.289015Z","end":"2026-07-15T03:18:56.438718Z","steps":["trace[1086086409] 'process raft request' (duration: 149.529767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:56.438865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.834383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0a3ae5d-9909-4a57-9344-fb5eb5dfec0d\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-07-15T03:18:56.438891Z","caller":"traceutil/trace.go:171","msg":"trace[222807022] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0a3ae5d-9909-4a57-9344-fb5eb5dfec0d; range_end:; response_count:1; response_revision:4836; }","duration":"127.908795ms","start":"2026-07-15T03:18:56.310974Z","end":"2026-07-15T03:18:56.438882Z","steps":["trace[222807022] 'agreement among raft nodes before linearized reading' (duration: 127.794242ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:56.570671Z","caller":"traceutil/trace.go:171","msg":"trace[1431825441] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"124.254863ms","start":"2026-07-15T03:18:56.446399Z","end":"2026-07-15T03:18:56.570654Z","steps":["trace[1431825441] 'process raft request' (duration: 123.871012ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:57.667172Z","caller":"traceutil/trace.go:171","msg":"trace[366767147] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"122.738043ms","start":"2026-07-15T03:18:57.544408Z","end":"2026-07-15T03:18:57.667146Z","steps":["trace[366767147] 'process raft request' (duration: 122.569288ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:57.75691Z","caller":"traceutil/trace.go:171","msg":"trace[1818519821] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5029; }","duration":"129.625744ms","start":"2026-07-15T03:18:57.627261Z","end":"2026-07-15T03:18:57.756887Z","steps":["trace[1818519821] 'read index received' (duration: 39.8255ms)","trace[1818519821] 'applied index is now lower than readState.Index' (duration: 89.799234ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:57.757035Z","caller":"traceutil/trace.go:171","msg":"trace[336960231] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"207.30081ms","start":"2026-07-15T03:18:57.549701Z","end":"2026-07-15T03:18:57.757002Z","steps":["trace[336960231] 'process raft request' (duration: 145.563769ms)","trace[336960231] 'compare' (duration: 61.478734ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:18:57.757166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.882171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:18:57.757227Z","caller":"traceutil/trace.go:171","msg":"trace[20279825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4849; }","duration":"129.989994ms","start":"2026-07-15T03:18:57.627222Z","end":"2026-07-15T03:18:57.757212Z","steps":["trace[20279825] 'agreement among raft nodes before linearized reading' (duration: 129.789529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:57.757277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.538805ms","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":"warn","ts":"2026-07-15T03:18:57.757277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.361264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T03:18:57.757363Z","caller":"traceutil/trace.go:171","msg":"trace[1433868330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4849; }","duration":"102.491629ms","start":"2026-07-15T03:18:57.654851Z","end":"2026-07-15T03:18:57.757343Z","steps":["trace[1433868330] 'agreement among raft nodes before linearized reading' (duration: 102.22498ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:57.757588Z","caller":"traceutil/trace.go:171","msg":"trace[1227435247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4849; }","duration":"123.957117ms","start":"2026-07-15T03:18:57.633618Z","end":"2026-07-15T03:18:57.757575Z","steps":["trace[1227435247] 'agreement among raft nodes before linearized reading' (duration: 123.409671ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:58.688075Z","caller":"traceutil/trace.go:171","msg":"trace[359995254] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"173.678246ms","start":"2026-07-15T03:18:58.514376Z","end":"2026-07-15T03:18:58.688055Z","steps":["trace[359995254] 'process raft request' (duration: 172.499312ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:59.377481Z","caller":"traceutil/trace.go:171","msg":"trace[1372985111] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"148.437924ms","start":"2026-07-15T03:18:59.229014Z","end":"2026-07-15T03:18:59.377452Z","steps":["trace[1372985111] 'process raft request' (duration: 63.632547ms)","trace[1372985111] 'compare' (duration: 84.689754ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:59.472316Z","caller":"traceutil/trace.go:171","msg":"trace[791180216] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"168.260067ms","start":"2026-07-15T03:18:59.30403Z","end":"2026-07-15T03:18:59.47229Z","steps":["trace[791180216] 'read index received' (duration: 168.00642ms)","trace[791180216] 'applied index is now lower than readState.Index' (duration: 252.367µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:59.472393Z","caller":"traceutil/trace.go:171","msg":"trace[2001360832] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"194.782348ms","start":"2026-07-15T03:18:59.277583Z","end":"2026-07-15T03:18:59.472366Z","steps":["trace[2001360832] 'process raft request' (duration: 194.543012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.473027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.973127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T03:18:59.473098Z","caller":"traceutil/trace.go:171","msg":"trace[1900409097] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4909; }","duration":"169.062809ms","start":"2026-07-15T03:18:59.30402Z","end":"2026-07-15T03:18:59.473082Z","steps":["trace[1900409097] 'agreement among raft nodes before linearized reading' (duration: 168.401971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.664514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.472432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:18:59.664809Z","caller":"traceutil/trace.go:171","msg":"trace[585349800] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5096; }","duration":"192.363179ms","start":"2026-07-15T03:18:59.472389Z","end":"2026-07-15T03:18:59.664752Z","steps":["trace[585349800] 'read index received' (duration: 42.590999ms)","trace[585349800] 'applied index is now lower than readState.Index' (duration: 149.77128ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:59.664829Z","caller":"traceutil/trace.go:171","msg":"trace[758623922] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"295.105668ms","start":"2026-07-15T03:18:59.369666Z","end":"2026-07-15T03:18:59.664772Z","steps":["trace[758623922] 'process raft request' (duration: 145.294226ms)","trace[758623922] 'compare' (duration: 149.200095ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:18:59.665056Z","caller":"traceutil/trace.go:171","msg":"trace[125592558] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"267.724823ms","start":"2026-07-15T03:18:59.397315Z","end":"2026-07-15T03:18:59.66504Z","steps":["trace[125592558] 'process raft request' (duration: 267.290571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.665175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.989707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:18:59.665324Z","caller":"traceutil/trace.go:171","msg":"trace[1149281511] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4911; }","duration":"275.166783ms","start":"2026-07-15T03:18:59.390138Z","end":"2026-07-15T03:18:59.665304Z","steps":["trace[1149281511] 'agreement among raft nodes before linearized reading' (duration: 274.880104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.665591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.947734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-n65kj\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-07-15T03:18:59.665699Z","caller":"traceutil/trace.go:171","msg":"trace[1390592895] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-n65kj; range_end:; response_count:1; response_revision:4911; }","duration":"271.101529ms","start":"2026-07-15T03:18:59.394571Z","end":"2026-07-15T03:18:59.665672Z","steps":["trace[1390592895] 'agreement among raft nodes before linearized reading' (duration: 270.47366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.666238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.661154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-07-15T03:18:59.666291Z","caller":"traceutil/trace.go:171","msg":"trace[1020115953] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4911; }","duration":"223.762257ms","start":"2026-07-15T03:18:59.442515Z","end":"2026-07-15T03:18:59.666278Z","steps":["trace[1020115953] 'agreement among raft nodes before linearized reading' (duration: 222.43988ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:59.726604Z","caller":"traceutil/trace.go:171","msg":"trace[518055998] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"248.306312ms","start":"2026-07-15T03:18:59.478278Z","end":"2026-07-15T03:18:59.726585Z","steps":["trace[518055998] 'process raft request' (duration: 248.189208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:18:59.72687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.015316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-15T03:18:59.727009Z","caller":"traceutil/trace.go:171","msg":"trace[1780650765] 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:4912; }","duration":"218.174081ms","start":"2026-07-15T03:18:59.508785Z","end":"2026-07-15T03:18:59.726959Z","steps":["trace[1780650765] 'agreement among raft nodes before linearized reading' (duration: 217.80417ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:59.780884Z","caller":"traceutil/trace.go:171","msg":"trace[641085788] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"108.782407ms","start":"2026-07-15T03:18:59.67206Z","end":"2026-07-15T03:18:59.780843Z","steps":["trace[641085788] 'process raft request' (duration: 107.887112ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:59.781055Z","caller":"traceutil/trace.go:171","msg":"trace[1509874530] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"105.212907ms","start":"2026-07-15T03:18:59.675769Z","end":"2026-07-15T03:18:59.780982Z","steps":["trace[1509874530] 'process raft request' (duration: 104.92733ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:18:59.917195Z","caller":"traceutil/trace.go:171","msg":"trace[706665567] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"109.224968ms","start":"2026-07-15T03:18:59.807949Z","end":"2026-07-15T03:18:59.917174Z","steps":["trace[706665567] 'process raft request' (duration: 92.77862ms)","trace[706665567] 'compare' (duration: 16.047767ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:00.628337Z","caller":"traceutil/trace.go:171","msg":"trace[1435550824] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"221.368533ms","start":"2026-07-15T03:19:00.40691Z","end":"2026-07-15T03:19:00.628278Z","steps":["trace[1435550824] 'process raft request' (duration: 221.232449ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:00.764963Z","caller":"traceutil/trace.go:171","msg":"trace[707180324] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"351.954692ms","start":"2026-07-15T03:19:00.412971Z","end":"2026-07-15T03:19:00.764925Z","steps":["trace[707180324] 'process raft request' (duration: 351.662723ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:00.764916Z","caller":"traceutil/trace.go:171","msg":"trace[709158474] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5111; }","duration":"266.552126ms","start":"2026-07-15T03:19:00.498338Z","end":"2026-07-15T03:19:00.76489Z","steps":["trace[709158474] 'read index received' (duration: 129.904468ms)","trace[709158474] 'applied index is now lower than readState.Index' (duration: 136.646568ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:00.765039Z","caller":"traceutil/trace.go:171","msg":"trace[833296164] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"311.46594ms","start":"2026-07-15T03:19:00.453547Z","end":"2026-07-15T03:19:00.765013Z","steps":["trace[833296164] 'process raft request' (duration: 311.276325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:00.765141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:00.41296Z","time spent":"352.088316ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9923,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:00.765186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:00.453528Z","time spent":"311.576193ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:00.765222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.878805ms","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-07-15T03:19:00.765262Z","caller":"traceutil/trace.go:171","msg":"trace[1403520321] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4927; }","duration":"266.965457ms","start":"2026-07-15T03:19:00.498282Z","end":"2026-07-15T03:19:00.765248Z","steps":["trace[1403520321] 'agreement among raft nodes before linearized reading' (duration: 266.796753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.057025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.560165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:19:07.057177Z","caller":"traceutil/trace.go:171","msg":"trace[1273064819] linearizableReadLoop","detail":"{readStateIndex:5214; appliedIndex:5213; }","duration":"741.757751ms","start":"2026-07-15T03:19:06.315383Z","end":"2026-07-15T03:19:07.05714Z","steps":["trace[1273064819] 'read index received' (duration: 278.932739ms)","trace[1273064819] 'applied index is now lower than readState.Index' (duration: 462.821512ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:07.057277Z","caller":"traceutil/trace.go:171","msg":"trace[71108113] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"834.179991ms","start":"2026-07-15T03:19:06.223078Z","end":"2026-07-15T03:19:07.057258Z","steps":["trace[71108113] 'process raft request' (duration: 371.303218ms)","trace[71108113] 'compare' (duration: 462.250706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:07.057362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:06.223053Z","time spent":"834.269343ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T03:19:07.057802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.410508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13c425bc-9601-4deb-9b80-7f72a8e27b51\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-15T03:19:07.057866Z","caller":"traceutil/trace.go:171","msg":"trace[1354862697] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-13c425bc-9601-4deb-9b80-7f72a8e27b51; range_end:; response_count:1; response_revision:5025; }","duration":"742.510091ms","start":"2026-07-15T03:19:06.315339Z","end":"2026-07-15T03:19:07.057849Z","steps":["trace[1354862697] 'agreement among raft nodes before linearized reading' (duration: 742.281895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.057919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:06.315321Z","time spent":"742.585973ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13c425bc-9601-4deb-9b80-7f72a8e27b51\" "} {"level":"info","ts":"2026-07-15T03:19:07.068161Z","caller":"traceutil/trace.go:171","msg":"trace[1366910637] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"282.620252ms","start":"2026-07-15T03:19:06.785522Z","end":"2026-07-15T03:19:07.068142Z","steps":["trace[1366910637] 'process raft request' (duration: 282.492739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.140886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.14402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:19:07.140977Z","caller":"traceutil/trace.go:171","msg":"trace[2085081550] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5027; }","duration":"150.322665ms","start":"2026-07-15T03:19:06.990633Z","end":"2026-07-15T03:19:07.140956Z","steps":["trace[2085081550] 'agreement among raft nodes before linearized reading' (duration: 150.090809ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:07.141059Z","caller":"traceutil/trace.go:171","msg":"trace[868348860] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"331.650947ms","start":"2026-07-15T03:19:06.809358Z","end":"2026-07-15T03:19:07.141009Z","steps":["trace[868348860] 'process raft request' (duration: 331.171853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.141334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:06.809328Z","time spent":"331.905784ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:07.141355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.250647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-15T03:19:07.141555Z","caller":"traceutil/trace.go:171","msg":"trace[591770731] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5027; }","duration":"685.492264ms","start":"2026-07-15T03:19:06.456028Z","end":"2026-07-15T03:19:07.141521Z","steps":["trace[591770731] 'agreement among raft nodes before linearized reading' (duration: 684.837476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.142304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:06.456019Z","time spent":"686.249325ms","remote":"127.0.0.1:47816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-15T03:19:07.142121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.665047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-07-15T03:19:07.142382Z","caller":"traceutil/trace.go:171","msg":"trace[1191126117] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5027; }","duration":"692.974265ms","start":"2026-07-15T03:19:06.449388Z","end":"2026-07-15T03:19:07.142362Z","steps":["trace[1191126117] 'agreement among raft nodes before linearized reading' (duration: 691.402661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:07.142443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:06.449367Z","time spent":"693.059567ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12996,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-07-15T03:19:07.26232Z","caller":"traceutil/trace.go:171","msg":"trace[1771365141] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"118.620267ms","start":"2026-07-15T03:19:07.143682Z","end":"2026-07-15T03:19:07.262302Z","steps":["trace[1771365141] 'process raft request' (duration: 118.490263ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:07.262694Z","caller":"traceutil/trace.go:171","msg":"trace[1746556992] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5217; }","duration":"116.103386ms","start":"2026-07-15T03:19:07.14658Z","end":"2026-07-15T03:19:07.262684Z","steps":["trace[1746556992] 'read index received' (duration: 116.099446ms)","trace[1746556992] 'applied index is now lower than readState.Index' (duration: 3.35µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:07.263124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.541859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-498fb692-5f89-49a9-a7d4-e91a34cc127d\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-15T03:19:07.263215Z","caller":"traceutil/trace.go:171","msg":"trace[1273442850] range","detail":"{range_begin:/registry/persistentvolumes/pvc-498fb692-5f89-49a9-a7d4-e91a34cc127d; range_end:; response_count:1; response_revision:5028; }","duration":"116.699044ms","start":"2026-07-15T03:19:07.146496Z","end":"2026-07-15T03:19:07.263195Z","steps":["trace[1273442850] 'agreement among raft nodes before linearized reading' (duration: 116.249631ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:07.302839Z","caller":"traceutil/trace.go:171","msg":"trace[135564263] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"148.445633ms","start":"2026-07-15T03:19:07.154357Z","end":"2026-07-15T03:19:07.302802Z","steps":["trace[135564263] 'process raft request' (duration: 148.201316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.163663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.193452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:19:08.163779Z","caller":"traceutil/trace.go:171","msg":"trace[893783915] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5035; }","duration":"493.391818ms","start":"2026-07-15T03:19:07.670358Z","end":"2026-07-15T03:19:08.16375Z","steps":["trace[893783915] 'count revisions from in-memory index tree' (duration: 493.073769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.16383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:07.670335Z","time spent":"493.482001ms","remote":"127.0.0.1:41302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:19:08.164077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.021992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:19:08.164495Z","caller":"traceutil/trace.go:171","msg":"trace[1775946156] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"478.915831ms","start":"2026-07-15T03:19:07.685559Z","end":"2026-07-15T03:19:08.164475Z","steps":["trace[1775946156] 'process raft request' (duration: 268.414116ms)","trace[1775946156] 'compare' (duration: 209.861147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:08.164565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:07.685533Z","time spent":"478.997574ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:19:08.164564Z","caller":"traceutil/trace.go:171","msg":"trace[1757277198] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5224; }","duration":"281.275771ms","start":"2026-07-15T03:19:07.883262Z","end":"2026-07-15T03:19:08.164538Z","steps":["trace[1757277198] 'read index received' (duration: 70.718153ms)","trace[1757277198] 'applied index is now lower than readState.Index' (duration: 210.554598ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:08.16457Z","caller":"traceutil/trace.go:171","msg":"trace[1543150797] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"355.904984ms","start":"2026-07-15T03:19:07.808646Z","end":"2026-07-15T03:19:08.164551Z","steps":["trace[1543150797] 'process raft request' (duration: 355.531033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.164684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:07.808616Z","time spent":"356.021627ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:08.164793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.006916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T03:19:08.164784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.522717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T03:19:08.164821Z","caller":"traceutil/trace.go:171","msg":"trace[1240874476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5037; }","duration":"172.053248ms","start":"2026-07-15T03:19:07.992758Z","end":"2026-07-15T03:19:08.164811Z","steps":["trace[1240874476] 'agreement among raft nodes before linearized reading' (duration: 172.005467ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:08.164832Z","caller":"traceutil/trace.go:171","msg":"trace[986892083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5037; }","duration":"281.608529ms","start":"2026-07-15T03:19:07.883209Z","end":"2026-07-15T03:19:08.164818Z","steps":["trace[986892083] 'agreement among raft nodes before linearized reading' (duration: 281.442305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.164889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.27216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T03:19:08.164919Z","caller":"traceutil/trace.go:171","msg":"trace[1005996255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5037; }","duration":"189.308982ms","start":"2026-07-15T03:19:07.9756Z","end":"2026-07-15T03:19:08.164909Z","steps":["trace[1005996255] 'agreement among raft nodes before linearized reading' (duration: 189.23895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.164816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.444326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-15T03:19:08.164951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.331873ms","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-07-15T03:19:08.165022Z","caller":"traceutil/trace.go:171","msg":"trace[1916860199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5037; }","duration":"178.513329ms","start":"2026-07-15T03:19:07.986493Z","end":"2026-07-15T03:19:08.165006Z","steps":["trace[1916860199] 'agreement among raft nodes before linearized reading' (duration: 178.283272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:08.165067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.414215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13c425bc-9601-4deb-9b80-7f72a8e27b51\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-07-15T03:19:08.164983Z","caller":"traceutil/trace.go:171","msg":"trace[1606092474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5037; }","duration":"189.651531ms","start":"2026-07-15T03:19:07.97532Z","end":"2026-07-15T03:19:08.164972Z","steps":["trace[1606092474] 'agreement among raft nodes before linearized reading' (duration: 189.371363ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:08.165118Z","caller":"traceutil/trace.go:171","msg":"trace[1912531462] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-13c425bc-9601-4deb-9b80-7f72a8e27b51; range_end:; response_count:1; response_revision:5037; }","duration":"101.471626ms","start":"2026-07-15T03:19:08.063631Z","end":"2026-07-15T03:19:08.165103Z","steps":["trace[1912531462] 'agreement among raft nodes before linearized reading' (duration: 101.297071ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:08.398997Z","caller":"traceutil/trace.go:171","msg":"trace[1040288603] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"229.936802ms","start":"2026-07-15T03:19:08.169037Z","end":"2026-07-15T03:19:08.398974Z","steps":["trace[1040288603] 'process raft request' (duration: 227.082822ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:08.399779Z","caller":"traceutil/trace.go:171","msg":"trace[709940679] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"229.791317ms","start":"2026-07-15T03:19:08.169945Z","end":"2026-07-15T03:19:08.399736Z","steps":["trace[709940679] 'process raft request' (duration: 229.628502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:08.400282Z","caller":"traceutil/trace.go:171","msg":"trace[1263667438] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"230.615451ms","start":"2026-07-15T03:19:08.169626Z","end":"2026-07-15T03:19:08.400241Z","steps":["trace[1263667438] 'process raft request' (duration: 229.246352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:10.936182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.220689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:19:10.936288Z","caller":"traceutil/trace.go:171","msg":"trace[1784965523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5085; }","duration":"192.376284ms","start":"2026-07-15T03:19:10.743886Z","end":"2026-07-15T03:19:10.936262Z","steps":["trace[1784965523] 'count revisions from in-memory index tree' (duration: 192.116967ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.357709Z","caller":"traceutil/trace.go:171","msg":"trace[107810906] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"168.140847ms","start":"2026-07-15T03:19:16.189536Z","end":"2026-07-15T03:19:16.357677Z","steps":["trace[107810906] 'process raft request' (duration: 167.839509ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.358105Z","caller":"traceutil/trace.go:171","msg":"trace[643659126] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5360; }","duration":"133.784998ms","start":"2026-07-15T03:19:16.224288Z","end":"2026-07-15T03:19:16.358073Z","steps":["trace[643659126] 'read index received' (duration: 133.059528ms)","trace[643659126] 'applied index is now lower than readState.Index' (duration: 724.64µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:16.358258Z","caller":"traceutil/trace.go:171","msg":"trace[510105334] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"168.000563ms","start":"2026-07-15T03:19:16.190232Z","end":"2026-07-15T03:19:16.358233Z","steps":["trace[510105334] 'process raft request' (duration: 167.694174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:16.358457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.148839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T03:19:16.358513Z","caller":"traceutil/trace.go:171","msg":"trace[106810405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5167; }","duration":"134.268002ms","start":"2026-07-15T03:19:16.224229Z","end":"2026-07-15T03:19:16.358497Z","steps":["trace[106810405] 'agreement among raft nodes before linearized reading' (duration: 133.954323ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.600601Z","caller":"traceutil/trace.go:171","msg":"trace[487240449] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"120.244028ms","start":"2026-07-15T03:19:16.480338Z","end":"2026-07-15T03:19:16.600582Z","steps":["trace[487240449] 'process raft request' (duration: 120.135965ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.634864Z","caller":"traceutil/trace.go:171","msg":"trace[692420926] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"152.99641ms","start":"2026-07-15T03:19:16.481848Z","end":"2026-07-15T03:19:16.634844Z","steps":["trace[692420926] 'process raft request' (duration: 152.617529ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.63523Z","caller":"traceutil/trace.go:171","msg":"trace[1533690345] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"152.186867ms","start":"2026-07-15T03:19:16.483026Z","end":"2026-07-15T03:19:16.635213Z","steps":["trace[1533690345] 'process raft request' (duration: 151.522609ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:16.635352Z","caller":"traceutil/trace.go:171","msg":"trace[2128932226] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"121.980926ms","start":"2026-07-15T03:19:16.513363Z","end":"2026-07-15T03:19:16.635344Z","steps":["trace[2128932226] 'process raft request' (duration: 121.222015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:21.631199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317221521608418,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T03:19:21.984293Z","caller":"traceutil/trace.go:171","msg":"trace[554175678] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"866.267156ms","start":"2026-07-15T03:19:21.117998Z","end":"2026-07-15T03:19:21.984266Z","steps":["trace[554175678] 'process raft request' (duration: 866.103902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:21.984785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.117965Z","time spent":"866.40981ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T03:19:22.009467Z","caller":"traceutil/trace.go:171","msg":"trace[1363578977] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5414; }","duration":"878.293416ms","start":"2026-07-15T03:19:21.131151Z","end":"2026-07-15T03:19:22.009445Z","steps":["trace[1363578977] 'read index received' (duration: 852.94876ms)","trace[1363578977] 'applied index is now lower than readState.Index' (duration: 25.343546ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:19:22.009567Z","caller":"traceutil/trace.go:171","msg":"trace[1003945439] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"827.110631ms","start":"2026-07-15T03:19:21.182449Z","end":"2026-07-15T03:19:22.00956Z","steps":["trace[1003945439] 'process raft request' (duration: 818.739674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.009658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.182419Z","time spent":"827.169783ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T03:19:22.009773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.836782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T03:19:22.009838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.602666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-15T03:19:22.009865Z","caller":"traceutil/trace.go:171","msg":"trace[1475327646] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5220; }","duration":"839.012747ms","start":"2026-07-15T03:19:21.170829Z","end":"2026-07-15T03:19:22.009842Z","steps":["trace[1475327646] 'agreement among raft nodes before linearized reading' (duration: 838.802011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.00991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.76797ms","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-07-15T03:19:22.009934Z","caller":"traceutil/trace.go:171","msg":"trace[1946196302] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5220; }","duration":"878.835662ms","start":"2026-07-15T03:19:21.131091Z","end":"2026-07-15T03:19:22.009927Z","steps":["trace[1946196302] 'agreement among raft nodes before linearized reading' (duration: 878.745399ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:22.009923Z","caller":"traceutil/trace.go:171","msg":"trace[1653441132] 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:5220; }","duration":"308.728ms","start":"2026-07-15T03:19:21.701173Z","end":"2026-07-15T03:19:22.009901Z","steps":["trace[1653441132] 'agreement among raft nodes before linearized reading' (duration: 308.479993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.009932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.170809Z","time spent":"839.10571ms","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":37,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-15T03:19:22.009959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.131072Z","time spent":"878.882143ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-15T03:19:22.009975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.701154Z","time spent":"308.809402ms","remote":"127.0.0.1:47816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-15T03:19:22.010105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.249724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:19:22.010137Z","caller":"traceutil/trace.go:171","msg":"trace[559095113] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5220; }","duration":"645.285925ms","start":"2026-07-15T03:19:21.36484Z","end":"2026-07-15T03:19:22.010126Z","steps":["trace[559095113] 'agreement among raft nodes before linearized reading' (duration: 645.195683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.009818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.380192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T03:19:22.01015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.104505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-15T03:19:22.010165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.36478Z","time spent":"645.376967ms","remote":"127.0.0.1:47944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":37,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-15T03:19:22.010204Z","caller":"traceutil/trace.go:171","msg":"trace[1381234560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5220; }","duration":"636.181518ms","start":"2026-07-15T03:19:21.374007Z","end":"2026-07-15T03:19:22.010188Z","steps":["trace[1381234560] 'agreement among raft nodes before linearized reading' (duration: 636.006193ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:22.010215Z","caller":"traceutil/trace.go:171","msg":"trace[858337808] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5220; }","duration":"119.774184ms","start":"2026-07-15T03:19:21.890402Z","end":"2026-07-15T03:19:22.010176Z","steps":["trace[858337808] 'agreement among raft nodes before linearized reading' (duration: 119.378162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.010251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.373997Z","time spent":"636.241859ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T03:19:22.010286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.130362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"info","ts":"2026-07-15T03:19:22.010362Z","caller":"traceutil/trace.go:171","msg":"trace[449987292] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5220; }","duration":"394.241405ms","start":"2026-07-15T03:19:21.6161Z","end":"2026-07-15T03:19:22.010342Z","steps":["trace[449987292] 'agreement among raft nodes before linearized reading' (duration: 394.011229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.010407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T03:19:21.616066Z","time spent":"394.331468ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16574,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-15T03:19:22.010805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.507861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:19:22.010868Z","caller":"traceutil/trace.go:171","msg":"trace[1366393756] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5220; }","duration":"145.606893ms","start":"2026-07-15T03:19:21.865248Z","end":"2026-07-15T03:19:22.010855Z","steps":["trace[1366393756] 'agreement among raft nodes before linearized reading' (duration: 144.649237ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:22.173874Z","caller":"traceutil/trace.go:171","msg":"trace[2065345425] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"150.313826ms","start":"2026-07-15T03:19:22.023534Z","end":"2026-07-15T03:19:22.173848Z","steps":["trace[2065345425] 'process raft request' (duration: 105.052308ms)","trace[2065345425] 'compare' (duration: 44.883888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:22.174019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.570853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:19:22.174086Z","caller":"traceutil/trace.go:171","msg":"trace[949475615] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5222; }","duration":"150.678577ms","start":"2026-07-15T03:19:22.023392Z","end":"2026-07-15T03:19:22.174071Z","steps":["trace[949475615] 'agreement among raft nodes before linearized reading' (duration: 150.519362ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:22.174084Z","caller":"traceutil/trace.go:171","msg":"trace[478955571] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"133.931883ms","start":"2026-07-15T03:19:22.039868Z","end":"2026-07-15T03:19:22.1738Z","steps":["trace[478955571] 'read index received' (duration: 88.587942ms)","trace[478955571] 'applied index is now lower than readState.Index' (duration: 45.343011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:19:22.174304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.166747ms","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-07-15T03:19:22.174391Z","caller":"traceutil/trace.go:171","msg":"trace[671326663] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5222; }","duration":"159.28653ms","start":"2026-07-15T03:19:22.015087Z","end":"2026-07-15T03:19:22.174374Z","steps":["trace[671326663] 'agreement among raft nodes before linearized reading' (duration: 158.996971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:19:22.174616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.973559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-15T03:19:22.174687Z","caller":"traceutil/trace.go:171","msg":"trace[805587997] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5222; }","duration":"132.082582ms","start":"2026-07-15T03:19:22.042592Z","end":"2026-07-15T03:19:22.174675Z","steps":["trace[805587997] 'agreement among raft nodes before linearized reading' (duration: 131.712041ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:19:40.486029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-07-15T03:19:40.565665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"77.816426ms","hash":1808824768,"current-db-size-bytes":52551680,"current-db-size":"53 MB","current-db-size-in-use-bytes":27713536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-15T03:19:40.565736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1808824768,"revision":2861,"compact-revision":-1} {"level":"info","ts":"2026-07-15T03:20:59.410692Z","caller":"traceutil/trace.go:171","msg":"trace[830714110] linearizableReadLoop","detail":"{readStateIndex:6214; appliedIndex:6213; }","duration":"208.133292ms","start":"2026-07-15T03:20:59.202539Z","end":"2026-07-15T03:20:59.410673Z","steps":["trace[830714110] 'read index received' (duration: 207.976458ms)","trace[830714110] 'applied index is now lower than readState.Index' (duration: 156.144µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:20:59.410793Z","caller":"traceutil/trace.go:171","msg":"trace[226609353] transaction","detail":"{read_only:false; response_revision:5995; number_of_response:1; }","duration":"208.972776ms","start":"2026-07-15T03:20:59.201802Z","end":"2026-07-15T03:20:59.410775Z","steps":["trace[226609353] 'process raft request' (duration: 208.75768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:20:59.4109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.335408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T03:20:59.410966Z","caller":"traceutil/trace.go:171","msg":"trace[1233015100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5995; }","duration":"208.454181ms","start":"2026-07-15T03:20:59.202498Z","end":"2026-07-15T03:20:59.410952Z","steps":["trace[1233015100] 'agreement among raft nodes before linearized reading' (duration: 208.296967ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:20.544441Z","caller":"traceutil/trace.go:171","msg":"trace[44430126] linearizableReadLoop","detail":"{readStateIndex:6322; appliedIndex:6321; }","duration":"112.480096ms","start":"2026-07-15T03:21:20.431936Z","end":"2026-07-15T03:21:20.544416Z","steps":["trace[44430126] 'read index received' (duration: 112.415794ms)","trace[44430126] 'applied index is now lower than readState.Index' (duration: 63.202µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:21:20.544574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.620119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T03:21:20.544554Z","caller":"traceutil/trace.go:171","msg":"trace[1614645438] transaction","detail":"{read_only:false; response_revision:6099; number_of_response:1; }","duration":"122.620292ms","start":"2026-07-15T03:21:20.421897Z","end":"2026-07-15T03:21:20.544517Z","steps":["trace[1614645438] 'process raft request' (duration: 122.356764ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:20.544614Z","caller":"traceutil/trace.go:171","msg":"trace[721448958] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6099; }","duration":"112.727582ms","start":"2026-07-15T03:21:20.431873Z","end":"2026-07-15T03:21:20.5446Z","steps":["trace[721448958] 'agreement among raft nodes before linearized reading' (duration: 112.632109ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:36.875823Z","caller":"traceutil/trace.go:171","msg":"trace[243063863] transaction","detail":"{read_only:false; response_revision:6179; number_of_response:1; }","duration":"133.465799ms","start":"2026-07-15T03:21:36.742331Z","end":"2026-07-15T03:21:36.875796Z","steps":["trace[243063863] 'process raft request' (duration: 133.323235ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:21:36.878429Z","caller":"traceutil/trace.go:171","msg":"trace[983875562] transaction","detail":"{read_only:false; response_revision:6180; number_of_response:1; }","duration":"115.495602ms","start":"2026-07-15T03:21:36.762917Z","end":"2026-07-15T03:21:36.878413Z","steps":["trace[983875562] 'process raft request' (duration: 115.322137ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:12.206246Z","caller":"traceutil/trace.go:171","msg":"trace[48553004] transaction","detail":"{read_only:false; response_revision:6865; number_of_response:1; }","duration":"128.010281ms","start":"2026-07-15T03:23:12.078215Z","end":"2026-07-15T03:23:12.206225Z","steps":["trace[48553004] 'process raft request' (duration: 77.764376ms)","trace[48553004] 'compare' (duration: 49.546371ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:23:18.703505Z","caller":"traceutil/trace.go:171","msg":"trace[575504500] transaction","detail":"{read_only:false; response_revision:6899; number_of_response:1; }","duration":"268.918906ms","start":"2026-07-15T03:23:18.434528Z","end":"2026-07-15T03:23:18.703447Z","steps":["trace[575504500] 'process raft request' (duration: 268.528668ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:20.221283Z","caller":"traceutil/trace.go:171","msg":"trace[2004505950] transaction","detail":"{read_only:false; response_revision:6910; number_of_response:1; }","duration":"144.899562ms","start":"2026-07-15T03:23:20.076355Z","end":"2026-07-15T03:23:20.221255Z","steps":["trace[2004505950] 'process raft request' (duration: 144.741129ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:28.508664Z","caller":"traceutil/trace.go:171","msg":"trace[2021177245] transaction","detail":"{read_only:false; response_revision:7003; number_of_response:1; }","duration":"135.720377ms","start":"2026-07-15T03:23:28.372917Z","end":"2026-07-15T03:23:28.508638Z","steps":["trace[2021177245] 'process raft request' (duration: 135.550914ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:29.027714Z","caller":"traceutil/trace.go:171","msg":"trace[1760230078] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"258.088295ms","start":"2026-07-15T03:23:28.769608Z","end":"2026-07-15T03:23:29.027696Z","steps":["trace[1760230078] 'process raft request' (duration: 257.976332ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:29.04722Z","caller":"traceutil/trace.go:171","msg":"trace[1473262514] transaction","detail":"{read_only:false; response_revision:7005; number_of_response:1; }","duration":"276.362508ms","start":"2026-07-15T03:23:28.770835Z","end":"2026-07-15T03:23:29.047198Z","steps":["trace[1473262514] 'process raft request' (duration: 276.024821ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:29.047572Z","caller":"traceutil/trace.go:171","msg":"trace[1487486492] transaction","detail":"{read_only:false; response_revision:7006; number_of_response:1; }","duration":"276.633123ms","start":"2026-07-15T03:23:28.770932Z","end":"2026-07-15T03:23:29.047565Z","steps":["trace[1487486492] 'process raft request' (duration: 276.030381ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:29.088795Z","caller":"traceutil/trace.go:171","msg":"trace[606258160] linearizableReadLoop","detail":"{readStateIndex:7262; appliedIndex:7259; }","duration":"269.397763ms","start":"2026-07-15T03:23:28.819376Z","end":"2026-07-15T03:23:29.088774Z","steps":["trace[606258160] 'read index received' (duration: 208.154641ms)","trace[606258160] 'applied index is now lower than readState.Index' (duration: 61.240282ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:23:29.089123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.732361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:23:29.089152Z","caller":"traceutil/trace.go:171","msg":"trace[1291859979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7006; }","duration":"269.795692ms","start":"2026-07-15T03:23:28.819349Z","end":"2026-07-15T03:23:29.089145Z","steps":["trace[1291859979] 'agreement among raft nodes before linearized reading' (duration: 269.736901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T03:23:29.089294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.886997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T03:23:29.08932Z","caller":"traceutil/trace.go:171","msg":"trace[42791525] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7006; }","duration":"150.916657ms","start":"2026-07-15T03:23:28.938397Z","end":"2026-07-15T03:23:29.089314Z","steps":["trace[42791525] 'agreement among raft nodes before linearized reading' (duration: 150.875497ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:39.255657Z","caller":"traceutil/trace.go:171","msg":"trace[344462478] transaction","detail":"{read_only:false; response_revision:7055; number_of_response:1; }","duration":"100.294359ms","start":"2026-07-15T03:23:39.155336Z","end":"2026-07-15T03:23:39.255631Z","steps":["trace[344462478] 'process raft request' (duration: 100.221558ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:39.256018Z","caller":"traceutil/trace.go:171","msg":"trace[624435419] transaction","detail":"{read_only:false; response_revision:7054; number_of_response:1; }","duration":"100.701758ms","start":"2026-07-15T03:23:39.155289Z","end":"2026-07-15T03:23:39.255991Z","steps":["trace[624435419] 'process raft request' (duration: 55.647721ms)","trace[624435419] 'compare' (duration: 44.331792ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:23:39.861511Z","caller":"traceutil/trace.go:171","msg":"trace[1380126385] transaction","detail":"{read_only:false; response_revision:7057; number_of_response:1; }","duration":"154.95607ms","start":"2026-07-15T03:23:39.705322Z","end":"2026-07-15T03:23:39.860278Z","steps":["trace[1380126385] 'process raft request' (duration: 154.727595ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:39.861913Z","caller":"traceutil/trace.go:171","msg":"trace[965952656] transaction","detail":"{read_only:false; response_revision:7058; number_of_response:1; }","duration":"149.444834ms","start":"2026-07-15T03:23:39.71083Z","end":"2026-07-15T03:23:39.860275Z","steps":["trace[965952656] 'process raft request' (duration: 149.367393ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:23:39.862394Z","caller":"traceutil/trace.go:171","msg":"trace[129159088] transaction","detail":"{read_only:false; response_revision:7059; number_of_response:1; }","duration":"121.531651ms","start":"2026-07-15T03:23:39.740853Z","end":"2026-07-15T03:23:39.862385Z","steps":["trace[129159088] 'process raft request' (duration: 119.378485ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:24:40.495842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5331} {"level":"info","ts":"2026-07-15T03:24:40.592895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5331,"took":"95.337166ms","hash":1151529075,"current-db-size-bytes":52551680,"current-db-size":"53 MB","current-db-size-in-use-bytes":18731008,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-15T03:24:40.593009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1151529075,"revision":5331,"compact-revision":2861} {"level":"warn","ts":"2026-07-15T03:25:20.02927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.676629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:25:20.029568Z","caller":"traceutil/trace.go:171","msg":"trace[2098531942] transaction","detail":"{read_only:false; response_revision:7591; number_of_response:1; }","duration":"220.273015ms","start":"2026-07-15T03:25:19.809282Z","end":"2026-07-15T03:25:20.029556Z","steps":["trace[2098531942] 'process raft request' (duration: 44.252887ms)","trace[2098531942] 'compare' (duration: 175.599878ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T03:25:20.02962Z","caller":"traceutil/trace.go:171","msg":"trace[101546523] transaction","detail":"{read_only:false; response_revision:7592; number_of_response:1; }","duration":"213.542325ms","start":"2026-07-15T03:25:19.816039Z","end":"2026-07-15T03:25:20.029581Z","steps":["trace[101546523] 'process raft request' (duration: 213.32733ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:25:29.818107Z","caller":"traceutil/trace.go:171","msg":"trace[1454273161] transaction","detail":"{read_only:false; response_revision:7641; number_of_response:1; }","duration":"154.327603ms","start":"2026-07-15T03:25:29.663743Z","end":"2026-07-15T03:25:29.818071Z","steps":["trace[1454273161] 'process raft request' (duration: 154.055607ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T03:25:30.020952Z","caller":"traceutil/trace.go:171","msg":"trace[1961833908] transaction","detail":"{read_only:false; response_revision:7642; number_of_response:1; }","duration":"180.051618ms","start":"2026-07-15T03:25:29.840874Z","end":"2026-07-15T03:25:30.020925Z","steps":["trace[1961833908] 'process raft request' (duration: 169.522926ms)","trace[1961833908] 'compare' (duration: 10.009269ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T03:25:30.247239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.209652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T03:25:30.247966Z","caller":"traceutil/trace.go:171","msg":"trace[120920380] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"164.778536ms","start":"2026-07-15T03:25:30.083146Z","end":"2026-07-15T03:25:30.247925Z","steps":["trace[120920380] 'process raft request' (duration: 53.761194ms)","trace[120920380] 'compare' (duration: 110.011937ms)"],"step_count":2}