{"level":"warn","ts":"2026-07-24T04:33:23.496438Z","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-24T04:33:23.496849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-24T04:33:23.496971Z","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-24T04:33:23.496982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-24T04:33:23.497003Z","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-24T04:33:23.497621Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-24T04:33:23.497687Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-24T04:33:23.502839Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.744475ms"} {"level":"info","ts":"2026-07-24T04:33:23.51905Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-24T04:33:23.519163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:33:23.519192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-24T04:33:23.51921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:33:23.519227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-24T04:33:23.519273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-24T04:33:23.53186Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:33:23.533103Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:33:23.534153Z","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-24T04:33:23.535972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:33:23.537182Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:33:23.538187Z","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-24T04:33:23.538304Z","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-24T04:33:23.538316Z","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-24T04:33:23.539791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-24T04:33:23.539897Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-24T04:33:23.543568Z","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-24T04:33:23.5437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-24T04:33:23.54372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-24T04:33:23.546285Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:33:23.546351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:33:24.520197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:33:24.520285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:33:24.520313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-24T04:33:24.520334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:33:24.520652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-24T04:33:24.520685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-24T04:33:24.5207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-24T04:33:24.522059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:33:24.52206Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:33:24.522094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:33:24.522128Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:33:24.522451Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:33:24.522618Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:33:24.523238Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:33:24.523342Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:33:24.523372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:33:24.525865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-24T04:33:24.527977Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:34:11.183846Z","caller":"traceutil/trace.go:171","msg":"trace[1975092923] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"105.039818ms","start":"2026-07-24T04:34:11.078785Z","end":"2026-07-24T04:34:11.183824Z","steps":["trace[1975092923] 'read index received' (duration: 104.879864ms)","trace[1975092923] 'applied index is now lower than readState.Index' (duration: 158.954µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:11.18394Z","caller":"traceutil/trace.go:171","msg":"trace[1873279034] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"152.414362ms","start":"2026-07-24T04:34:11.031488Z","end":"2026-07-24T04:34:11.183902Z","steps":["trace[1873279034] 'process raft request' (duration: 152.166745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:11.183997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.193412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:11.18407Z","caller":"traceutil/trace.go:171","msg":"trace[234558159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"105.355077ms","start":"2026-07-24T04:34:11.078704Z","end":"2026-07-24T04:34:11.184059Z","steps":["trace[234558159] 'agreement among raft nodes before linearized reading' (duration: 105.224414ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:11.718202Z","caller":"traceutil/trace.go:171","msg":"trace[1888892234] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"111.025369ms","start":"2026-07-24T04:34:11.607147Z","end":"2026-07-24T04:34:11.718173Z","steps":["trace[1888892234] 'process raft request' (duration: 110.843724ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:11.7261Z","caller":"traceutil/trace.go:171","msg":"trace[1520772597] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"100.730897ms","start":"2026-07-24T04:34:11.625311Z","end":"2026-07-24T04:34:11.726042Z","steps":["trace[1520772597] 'process raft request' (duration: 100.436479ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:11.726483Z","caller":"traceutil/trace.go:171","msg":"trace[1672691915] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"101.088797ms","start":"2026-07-24T04:34:11.625377Z","end":"2026-07-24T04:34:11.726466Z","steps":["trace[1672691915] 'process raft request' (duration: 100.553822ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:17.98958Z","caller":"traceutil/trace.go:171","msg":"trace[126528294] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"258.268975ms","start":"2026-07-24T04:34:17.731278Z","end":"2026-07-24T04:34:17.989547Z","steps":["trace[126528294] 'read index received' (duration: 258.0759ms)","trace[126528294] 'applied index is now lower than readState.Index' (duration: 191.645µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:17.989666Z","caller":"traceutil/trace.go:171","msg":"trace[720297800] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"284.6939ms","start":"2026-07-24T04:34:17.704948Z","end":"2026-07-24T04:34:17.989642Z","steps":["trace[720297800] 'process raft request' (duration: 284.284768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:17.989811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.456821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-24T04:34:17.98987Z","caller":"traceutil/trace.go:171","msg":"trace[211251590] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:614; }","duration":"258.597714ms","start":"2026-07-24T04:34:17.731263Z","end":"2026-07-24T04:34:17.98986Z","steps":["trace[211251590] 'agreement among raft nodes before linearized reading' (duration: 258.41805ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:18.054853Z","caller":"traceutil/trace.go:171","msg":"trace[1113430294] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"135.653222ms","start":"2026-07-24T04:34:17.91915Z","end":"2026-07-24T04:34:18.054803Z","steps":["trace[1113430294] 'process raft request' (duration: 134.83644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:18.055223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.657973ms","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-24T04:34:18.055458Z","caller":"traceutil/trace.go:171","msg":"trace[1685574850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"168.967602ms","start":"2026-07-24T04:34:17.886421Z","end":"2026-07-24T04:34:18.055388Z","steps":["trace[1685574850] 'agreement among raft nodes before linearized reading' (duration: 168.288824ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:18.264294Z","caller":"traceutil/trace.go:171","msg":"trace[542577079] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"202.761233ms","start":"2026-07-24T04:34:18.0615Z","end":"2026-07-24T04:34:18.264261Z","steps":["trace[542577079] 'process raft request' (duration: 115.076613ms)","trace[542577079] 'compare' (duration: 87.181677ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:18.264438Z","caller":"traceutil/trace.go:171","msg":"trace[1948056916] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"169.698407ms","start":"2026-07-24T04:34:18.094682Z","end":"2026-07-24T04:34:18.26438Z","steps":["trace[1948056916] 'read index received' (duration: 81.849983ms)","trace[1948056916] 'applied index is now lower than readState.Index' (duration: 87.844934ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:18.264661Z","caller":"traceutil/trace.go:171","msg":"trace[2022066934] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"153.478052ms","start":"2026-07-24T04:34:18.111086Z","end":"2026-07-24T04:34:18.264564Z","steps":["trace[2022066934] 'process raft request' (duration: 153.04496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:18.264771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.075228ms","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-24T04:34:18.264871Z","caller":"traceutil/trace.go:171","msg":"trace[1726788152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"170.236632ms","start":"2026-07-24T04:34:18.094618Z","end":"2026-07-24T04:34:18.264855Z","steps":["trace[1726788152] 'agreement among raft nodes before linearized reading' (duration: 169.926304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:19.125431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.856066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:34:19.125572Z","caller":"traceutil/trace.go:171","msg":"trace[2057409589] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"842.890101ms","start":"2026-07-24T04:34:18.282661Z","end":"2026-07-24T04:34:19.125551Z","steps":["trace[2057409589] 'read index received' (duration: 182.080779ms)","trace[2057409589] 'applied index is now lower than readState.Index' (duration: 660.807272ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:19.125633Z","caller":"traceutil/trace.go:171","msg":"trace[420751863] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"849.314374ms","start":"2026-07-24T04:34:18.276271Z","end":"2026-07-24T04:34:19.125585Z","steps":["trace[420751863] 'process raft request' (duration: 188.560494ms)","trace[420751863] 'compare' (duration: 659.63946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:34:19.125701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.027314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:19.12577Z","caller":"traceutil/trace.go:171","msg":"trace[1163284314] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"843.149298ms","start":"2026-07-24T04:34:18.282601Z","end":"2026-07-24T04:34:19.12575Z","steps":["trace[1163284314] 'agreement among raft nodes before linearized reading' (duration: 843.041375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:19.125804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:34:18.282573Z","time spent":"843.22426ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-24T04:34:19.125848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.029865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:34:19.125865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:34:18.276203Z","time spent":"849.529229ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:34:19.125888Z","caller":"traceutil/trace.go:171","msg":"trace[848685567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"359.143178ms","start":"2026-07-24T04:34:18.766734Z","end":"2026-07-24T04:34:19.125877Z","steps":["trace[848685567] 'agreement among raft nodes before linearized reading' (duration: 358.925982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:19.125937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:34:18.766705Z","time spent":"359.22313ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T04:34:34.726342Z","caller":"traceutil/trace.go:171","msg":"trace[261641619] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"195.012933ms","start":"2026-07-24T04:34:34.53129Z","end":"2026-07-24T04:34:34.726303Z","steps":["trace[261641619] 'read index received' (duration: 150.575546ms)","trace[261641619] 'applied index is now lower than readState.Index' (duration: 44.434957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:34:34.727052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.098674ms","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":"warn","ts":"2026-07-24T04:34:34.727098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.437205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.727174Z","caller":"traceutil/trace.go:171","msg":"trace[283413622] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:778; }","duration":"137.277359ms","start":"2026-07-24T04:34:34.589877Z","end":"2026-07-24T04:34:34.727155Z","steps":["trace[283413622] 'agreement among raft nodes before linearized reading' (duration: 137.043793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:34.72719Z","caller":"traceutil/trace.go:171","msg":"trace[1952072533] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:778; }","duration":"117.55696ms","start":"2026-07-24T04:34:34.609613Z","end":"2026-07-24T04:34:34.72717Z","steps":["trace[1952072533] 'agreement among raft nodes before linearized reading' (duration: 117.394965ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:34.727561Z","caller":"traceutil/trace.go:171","msg":"trace[440931920] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"206.374024ms","start":"2026-07-24T04:34:34.52116Z","end":"2026-07-24T04:34:34.727534Z","steps":["trace[440931920] 'process raft request' (duration: 160.764368ms)","trace[440931920] 'compare' (duration: 44.205222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:34:34.727521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.907047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.727642Z","caller":"traceutil/trace.go:171","msg":"trace[1737580955] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:778; }","duration":"118.067321ms","start":"2026-07-24T04:34:34.609563Z","end":"2026-07-24T04:34:34.72763Z","steps":["trace[1737580955] 'agreement among raft nodes before linearized reading' (duration: 117.928258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.727423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.452308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:34:34.726894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.518165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.727759Z","caller":"traceutil/trace.go:171","msg":"trace[1597376933] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:778; }","duration":"154.836956ms","start":"2026-07-24T04:34:34.572896Z","end":"2026-07-24T04:34:34.727733Z","steps":["trace[1597376933] 'agreement among raft nodes before linearized reading' (duration: 154.342665ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:34.727778Z","caller":"traceutil/trace.go:171","msg":"trace[443604254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:778; }","duration":"196.467018ms","start":"2026-07-24T04:34:34.531296Z","end":"2026-07-24T04:34:34.727763Z","steps":["trace[443604254] 'agreement among raft nodes before linearized reading' (duration: 195.492605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.72779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.942304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.727851Z","caller":"traceutil/trace.go:171","msg":"trace[1250814041] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:778; }","duration":"138.011017ms","start":"2026-07-24T04:34:34.589814Z","end":"2026-07-24T04:34:34.727825Z","steps":["trace[1250814041] 'agreement among raft nodes before linearized reading' (duration: 137.944305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.727906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.483352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.727963Z","caller":"traceutil/trace.go:171","msg":"trace[1759520131] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:778; }","duration":"118.554844ms","start":"2026-07-24T04:34:34.609395Z","end":"2026-07-24T04:34:34.72795Z","steps":["trace[1759520131] 'agreement among raft nodes before linearized reading' (duration: 118.475042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.727976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.902273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.728009Z","caller":"traceutil/trace.go:171","msg":"trace[410363353] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:778; }","duration":"137.977315ms","start":"2026-07-24T04:34:34.590023Z","end":"2026-07-24T04:34:34.728Z","steps":["trace[410363353] 'agreement among raft nodes before linearized reading' (duration: 137.920584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.728012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.532111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.728047Z","caller":"traceutil/trace.go:171","msg":"trace[1696353433] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:778; }","duration":"178.588862ms","start":"2026-07-24T04:34:34.549448Z","end":"2026-07-24T04:34:34.728037Z","steps":["trace[1696353433] 'agreement among raft nodes before linearized reading' (duration: 178.525051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.728124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.31717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.72816Z","caller":"traceutil/trace.go:171","msg":"trace[2072020308] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:778; }","duration":"179.398751ms","start":"2026-07-24T04:34:34.548753Z","end":"2026-07-24T04:34:34.728151Z","steps":["trace[2072020308] 'agreement among raft nodes before linearized reading' (duration: 179.33465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.727728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.423787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.728213Z","caller":"traceutil/trace.go:171","msg":"trace[215899979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:778; }","duration":"196.932509ms","start":"2026-07-24T04:34:34.531271Z","end":"2026-07-24T04:34:34.728204Z","steps":["trace[215899979] 'agreement among raft nodes before linearized reading' (duration: 196.408956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.728256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.094703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:34:34.728298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.454823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.728331Z","caller":"traceutil/trace.go:171","msg":"trace[1072746904] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:778; }","duration":"179.500493ms","start":"2026-07-24T04:34:34.54882Z","end":"2026-07-24T04:34:34.728321Z","steps":["trace[1072746904] 'agreement among raft nodes before linearized reading' (duration: 179.455272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:34.72768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.623928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:34:34.728601Z","caller":"traceutil/trace.go:171","msg":"trace[441124367] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:778; }","duration":"154.595941ms","start":"2026-07-24T04:34:34.573994Z","end":"2026-07-24T04:34:34.72859Z","steps":["trace[441124367] 'agreement among raft nodes before linearized reading' (duration: 153.506475ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:34.728301Z","caller":"traceutil/trace.go:171","msg":"trace[218469031] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:778; }","duration":"155.198625ms","start":"2026-07-24T04:34:34.573091Z","end":"2026-07-24T04:34:34.72829Z","steps":["trace[218469031] 'agreement among raft nodes before linearized reading' (duration: 155.116673ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:09.626967Z","caller":"traceutil/trace.go:171","msg":"trace[1291340124] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"195.442051ms","start":"2026-07-24T04:35:09.431488Z","end":"2026-07-24T04:35:09.62693Z","steps":["trace[1291340124] 'process raft request' (duration: 192.142036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:26.036523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.076959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:35:26.03659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.184646ms","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-24T04:35:26.036657Z","caller":"traceutil/trace.go:171","msg":"trace[218349016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"299.249561ms","start":"2026-07-24T04:35:25.73737Z","end":"2026-07-24T04:35:26.03662Z","steps":["trace[218349016] 'range keys from in-memory index tree' (duration: 298.845075ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:26.036701Z","caller":"traceutil/trace.go:171","msg":"trace[1554621246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"307.359118ms","start":"2026-07-24T04:35:25.729319Z","end":"2026-07-24T04:35:26.036678Z","steps":["trace[1554621246] 'range keys from in-memory index tree' (duration: 306.983391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:26.036789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:25.72929Z","time spent":"307.487932ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T04:35:29.602235Z","caller":"traceutil/trace.go:171","msg":"trace[58794706] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"113.909774ms","start":"2026-07-24T04:35:29.488297Z","end":"2026-07-24T04:35:29.602206Z","steps":["trace[58794706] 'process raft request' (duration: 102.022772ms)","trace[58794706] 'compare' (duration: 11.605967ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:29.827642Z","caller":"traceutil/trace.go:171","msg":"trace[302328314] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"160.986661ms","start":"2026-07-24T04:35:29.666592Z","end":"2026-07-24T04:35:29.827578Z","steps":["trace[302328314] 'process raft request' (duration: 98.612512ms)","trace[302328314] 'compare' (duration: 62.175836ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:33.245167Z","caller":"traceutil/trace.go:171","msg":"trace[2034962848] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"104.362347ms","start":"2026-07-24T04:35:33.140775Z","end":"2026-07-24T04:35:33.245138Z","steps":["trace[2034962848] 'process raft request' (duration: 104.163324ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:53.148526Z","caller":"traceutil/trace.go:171","msg":"trace[593849222] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"200.583513ms","start":"2026-07-24T04:35:52.9479Z","end":"2026-07-24T04:35:53.148483Z","steps":["trace[593849222] 'process raft request' (duration: 200.229186ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:58.524266Z","caller":"traceutil/trace.go:171","msg":"trace[149464906] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"145.935139ms","start":"2026-07-24T04:35:58.378298Z","end":"2026-07-24T04:35:58.524234Z","steps":["trace[149464906] 'read index received' (duration: 116.976851ms)","trace[149464906] 'applied index is now lower than readState.Index' (duration: 28.956928ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:58.524467Z","caller":"traceutil/trace.go:171","msg":"trace[2094112493] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"149.475017ms","start":"2026-07-24T04:35:58.374915Z","end":"2026-07-24T04:35:58.52439Z","steps":["trace[2094112493] 'process raft request' (duration: 120.346296ms)","trace[2094112493] 'compare' (duration: 28.836386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:58.52452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.183785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-24T04:35:58.524587Z","caller":"traceutil/trace.go:171","msg":"trace[94059544] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1810; }","duration":"146.316476ms","start":"2026-07-24T04:35:58.378259Z","end":"2026-07-24T04:35:58.524576Z","steps":["trace[94059544] 'agreement among raft nodes before linearized reading' (duration: 146.093093ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:58.542726Z","caller":"traceutil/trace.go:171","msg":"trace[367818900] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"115.745072ms","start":"2026-07-24T04:35:58.426944Z","end":"2026-07-24T04:35:58.542689Z","steps":["trace[367818900] 'process raft request' (duration: 115.567518ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.278691Z","caller":"traceutil/trace.go:171","msg":"trace[214867505] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"185.307849ms","start":"2026-07-24T04:35:59.093337Z","end":"2026-07-24T04:35:59.278645Z","steps":["trace[214867505] 'process raft request' (duration: 107.313661ms)","trace[214867505] 'compare' (duration: 77.829655ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:59.278742Z","caller":"traceutil/trace.go:171","msg":"trace[123392611] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"102.92009ms","start":"2026-07-24T04:35:59.175788Z","end":"2026-07-24T04:35:59.278708Z","steps":["trace[123392611] 'read index received' (duration: 24.956373ms)","trace[123392611] 'applied index is now lower than readState.Index' (duration: 77.961727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:59.280618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.80184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T04:35:59.280696Z","caller":"traceutil/trace.go:171","msg":"trace[1154911775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1819; }","duration":"104.942793ms","start":"2026-07-24T04:35:59.175733Z","end":"2026-07-24T04:35:59.280676Z","steps":["trace[1154911775] 'agreement among raft nodes before linearized reading' (duration: 103.099863ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.482511Z","caller":"traceutil/trace.go:171","msg":"trace[1112477668] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"198.368109ms","start":"2026-07-24T04:35:59.2841Z","end":"2026-07-24T04:35:59.482468Z","steps":["trace[1112477668] 'process raft request' (duration: 161.667777ms)","trace[1112477668] 'compare' (duration: 36.129242ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:59.482755Z","caller":"traceutil/trace.go:171","msg":"trace[1871007504] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"198.142015ms","start":"2026-07-24T04:35:59.284588Z","end":"2026-07-24T04:35:59.48273Z","steps":["trace[1871007504] 'process raft request' (duration: 197.982113ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.482753Z","caller":"traceutil/trace.go:171","msg":"trace[1749905151] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"198.258376ms","start":"2026-07-24T04:35:59.284461Z","end":"2026-07-24T04:35:59.482719Z","steps":["trace[1749905151] 'process raft request' (duration: 197.738559ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.482923Z","caller":"traceutil/trace.go:171","msg":"trace[954211629] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1902; }","duration":"194.258903ms","start":"2026-07-24T04:35:59.288649Z","end":"2026-07-24T04:35:59.482908Z","steps":["trace[954211629] 'read index received' (duration: 55.230881ms)","trace[954211629] 'applied index is now lower than readState.Index' (duration: 139.026582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:59.483119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.455276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-24T04:35:59.483156Z","caller":"traceutil/trace.go:171","msg":"trace[355723446] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"194.546648ms","start":"2026-07-24T04:35:59.2886Z","end":"2026-07-24T04:35:59.483147Z","steps":["trace[355723446] 'agreement among raft nodes before linearized reading' (duration: 194.391535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:59.483401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.262992ms","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-24T04:35:59.483444Z","caller":"traceutil/trace.go:171","msg":"trace[1739531680] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"139.5497ms","start":"2026-07-24T04:35:59.343882Z","end":"2026-07-24T04:35:59.483432Z","steps":["trace[1739531680] 'process raft request' (duration: 138.792858ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.483458Z","caller":"traceutil/trace.go:171","msg":"trace[1593986758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1824; }","duration":"127.397083ms","start":"2026-07-24T04:35:59.356048Z","end":"2026-07-24T04:35:59.483445Z","steps":["trace[1593986758] 'agreement among raft nodes before linearized reading' (duration: 127.19423ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.844602Z","caller":"traceutil/trace.go:171","msg":"trace[395289256] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"185.583483ms","start":"2026-07-24T04:35:59.658981Z","end":"2026-07-24T04:35:59.844565Z","steps":["trace[395289256] 'read index received' (duration: 141.465031ms)","trace[395289256] 'applied index is now lower than readState.Index' (duration: 44.117472ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:59.844732Z","caller":"traceutil/trace.go:171","msg":"trace[109556851] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"228.030456ms","start":"2026-07-24T04:35:59.616644Z","end":"2026-07-24T04:35:59.844674Z","steps":["trace[109556851] 'process raft request' (duration: 183.833094ms)","trace[109556851] 'compare' (duration: 43.899538ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:59.844979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.973599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-24T04:35:59.845028Z","caller":"traceutil/trace.go:171","msg":"trace[780240480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1828; }","duration":"186.087851ms","start":"2026-07-24T04:35:59.658927Z","end":"2026-07-24T04:35:59.845014Z","steps":["trace[780240480] 'agreement among raft nodes before linearized reading' (duration: 185.830738ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:59.851174Z","caller":"traceutil/trace.go:171","msg":"trace[1125316578] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"191.550959ms","start":"2026-07-24T04:35:59.659586Z","end":"2026-07-24T04:35:59.851137Z","steps":["trace[1125316578] 'process raft request' (duration: 191.342055ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.197031Z","caller":"traceutil/trace.go:171","msg":"trace[2056720600] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"140.210986ms","start":"2026-07-24T04:36:00.056702Z","end":"2026-07-24T04:36:00.196913Z","steps":["trace[2056720600] 'process raft request' (duration: 47.585305ms)","trace[2056720600] 'compare' (duration: 92.366756ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:00.197439Z","caller":"traceutil/trace.go:171","msg":"trace[441885561] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"140.489659ms","start":"2026-07-24T04:36:00.056904Z","end":"2026-07-24T04:36:00.197394Z","steps":["trace[441885561] 'process raft request' (duration: 139.919239ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.197947Z","caller":"traceutil/trace.go:171","msg":"trace[1365649115] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"133.215203ms","start":"2026-07-24T04:36:00.064715Z","end":"2026-07-24T04:36:00.19793Z","steps":["trace[1365649115] 'process raft request' (duration: 133.141332ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.198105Z","caller":"traceutil/trace.go:171","msg":"trace[1587765189] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"133.399765ms","start":"2026-07-24T04:36:00.06463Z","end":"2026-07-24T04:36:00.19803Z","steps":["trace[1587765189] 'process raft request' (duration: 132.733884ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.20308Z","caller":"traceutil/trace.go:171","msg":"trace[1185188782] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1932; }","duration":"128.136981ms","start":"2026-07-24T04:36:00.074927Z","end":"2026-07-24T04:36:00.203064Z","steps":["trace[1185188782] 'read index received' (duration: 29.373132ms)","trace[1185188782] 'applied index is now lower than readState.Index' (duration: 98.762829ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:00.203328Z","caller":"traceutil/trace.go:171","msg":"trace[545898326] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"130.601491ms","start":"2026-07-24T04:36:00.072687Z","end":"2026-07-24T04:36:00.203288Z","steps":["trace[545898326] 'process raft request' (duration: 130.087203ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.203344Z","caller":"traceutil/trace.go:171","msg":"trace[1376381860] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"128.903743ms","start":"2026-07-24T04:36:00.074395Z","end":"2026-07-24T04:36:00.203299Z","steps":["trace[1376381860] 'process raft request' (duration: 128.627428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.20345Z","caller":"traceutil/trace.go:171","msg":"trace[1345593884] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"132.237797ms","start":"2026-07-24T04:36:00.071193Z","end":"2026-07-24T04:36:00.20343Z","steps":["trace[1345593884] 'process raft request' (duration: 131.438595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:00.203452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.542348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:36:00.203538Z","caller":"traceutil/trace.go:171","msg":"trace[1138918552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"120.670651ms","start":"2026-07-24T04:36:00.082847Z","end":"2026-07-24T04:36:00.203517Z","steps":["trace[1138918552] 'agreement among raft nodes before linearized reading' (duration: 120.493228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:00.204168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.226359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130862"} {"level":"info","ts":"2026-07-24T04:36:00.204218Z","caller":"traceutil/trace.go:171","msg":"trace[1972142558] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1856; }","duration":"129.31744ms","start":"2026-07-24T04:36:00.074888Z","end":"2026-07-24T04:36:00.204206Z","steps":["trace[1972142558] 'agreement among raft nodes before linearized reading' (duration: 128.412225ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.395966Z","caller":"traceutil/trace.go:171","msg":"trace[1882000154] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"101.049155ms","start":"2026-07-24T04:36:00.29489Z","end":"2026-07-24T04:36:00.395939Z","steps":["trace[1882000154] 'read index received' (duration: 23.175152ms)","trace[1882000154] 'applied index is now lower than readState.Index' (duration: 77.872713ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:00.39624Z","caller":"traceutil/trace.go:171","msg":"trace[70957424] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"102.58578ms","start":"2026-07-24T04:36:00.293637Z","end":"2026-07-24T04:36:00.396223Z","steps":["trace[70957424] 'process raft request' (duration: 23.854954ms)","trace[70957424] 'compare' (duration: 78.175678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:00.396465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.548303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:36:00.3965Z","caller":"traceutil/trace.go:171","msg":"trace[97082737] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1864; }","duration":"101.634925ms","start":"2026-07-24T04:36:00.294856Z","end":"2026-07-24T04:36:00.396491Z","steps":["trace[97082737] 'agreement among raft nodes before linearized reading' (duration: 101.552053ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.398877Z","caller":"traceutil/trace.go:171","msg":"trace[332097456] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"102.835974ms","start":"2026-07-24T04:36:00.296007Z","end":"2026-07-24T04:36:00.398843Z","steps":["trace[332097456] 'process raft request' (duration: 102.682492ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.399062Z","caller":"traceutil/trace.go:171","msg":"trace[1163374839] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"103.928391ms","start":"2026-07-24T04:36:00.29512Z","end":"2026-07-24T04:36:00.399049Z","steps":["trace[1163374839] 'process raft request' (duration: 103.416164ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.520053Z","caller":"traceutil/trace.go:171","msg":"trace[112688026] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"107.414657ms","start":"2026-07-24T04:36:00.412614Z","end":"2026-07-24T04:36:00.520028Z","steps":["trace[112688026] 'read index received' (duration: 107.203974ms)","trace[112688026] 'applied index is now lower than readState.Index' (duration: 209.753µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:00.520222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.59459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2524e51-55b7-435e-9232-5c94793a4cb7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-24T04:36:00.520199Z","caller":"traceutil/trace.go:171","msg":"trace[1598741203] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"108.591096ms","start":"2026-07-24T04:36:00.411553Z","end":"2026-07-24T04:36:00.520144Z","steps":["trace[1598741203] 'process raft request' (duration: 108.265061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:00.520262Z","caller":"traceutil/trace.go:171","msg":"trace[1318726231] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2524e51-55b7-435e-9232-5c94793a4cb7; range_end:; response_count:1; response_revision:1867; }","duration":"107.689701ms","start":"2026-07-24T04:36:00.412559Z","end":"2026-07-24T04:36:00.520249Z","steps":["trace[1318726231] 'agreement among raft nodes before linearized reading' (duration: 107.554459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.797332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.277971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:36:02.797573Z","caller":"traceutil/trace.go:171","msg":"trace[242968637] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"191.702387ms","start":"2026-07-24T04:36:02.605845Z","end":"2026-07-24T04:36:02.797547Z","steps":["trace[242968637] 'process raft request' (duration: 191.629436ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:02.797576Z","caller":"traceutil/trace.go:171","msg":"trace[335463278] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"574.046836ms","start":"2026-07-24T04:36:02.223487Z","end":"2026-07-24T04:36:02.797534Z","steps":["trace[335463278] 'process raft request' (duration: 102.44091ms)","trace[335463278] 'compare' (duration: 471.030216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:02.797821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.223449Z","time spent":"574.216258ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:02.798365Z","caller":"traceutil/trace.go:171","msg":"trace[335028830] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"526.034037ms","start":"2026-07-24T04:36:02.272318Z","end":"2026-07-24T04:36:02.798352Z","steps":["trace[335028830] 'read index received' (duration: 53.600758ms)","trace[335028830] 'applied index is now lower than readState.Index' (duration: 472.431669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:02.798588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.277671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-07-24T04:36:02.798641Z","caller":"traceutil/trace.go:171","msg":"trace[802140664] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1892; }","duration":"526.372233ms","start":"2026-07-24T04:36:02.272256Z","end":"2026-07-24T04:36:02.798628Z","steps":["trace[802140664] 'agreement among raft nodes before linearized reading' (duration: 526.17044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.798681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.27223Z","time spent":"526.437093ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10690,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-07-24T04:36:02.832905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.996188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:36:02.833029Z","caller":"traceutil/trace.go:171","msg":"trace[345720573] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1892; }","duration":"203.18823ms","start":"2026-07-24T04:36:02.629822Z","end":"2026-07-24T04:36:02.83301Z","steps":["trace[345720573] 'agreement among raft nodes before linearized reading' (duration: 203.000128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.833128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.223318ms","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-24T04:36:02.833253Z","caller":"traceutil/trace.go:171","msg":"trace[1587849699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1892; }","duration":"429.381229ms","start":"2026-07-24T04:36:02.403826Z","end":"2026-07-24T04:36:02.833207Z","steps":["trace[1587849699] 'agreement among raft nodes before linearized reading' (duration: 429.045244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.833227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.639542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2524e51-55b7-435e-9232-5c94793a4cb7\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-24T04:36:02.83331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.403806Z","time spent":"429.494632ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T04:36:02.833322Z","caller":"traceutil/trace.go:171","msg":"trace[315124180] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2524e51-55b7-435e-9232-5c94793a4cb7; range_end:; response_count:1; response_revision:1892; }","duration":"302.817745ms","start":"2026-07-24T04:36:02.530485Z","end":"2026-07-24T04:36:02.833303Z","steps":["trace[315124180] 'agreement among raft nodes before linearized reading' (duration: 302.49959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.833364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.530466Z","time spent":"302.891375ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2524e51-55b7-435e-9232-5c94793a4cb7\" "} {"level":"warn","ts":"2026-07-24T04:36:02.833565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.13309ms","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-24T04:36:02.833734Z","caller":"traceutil/trace.go:171","msg":"trace[64266626] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1892; }","duration":"503.354874ms","start":"2026-07-24T04:36:02.33035Z","end":"2026-07-24T04:36:02.833705Z","steps":["trace[64266626] 'agreement among raft nodes before linearized reading' (duration: 502.607402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.833887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.881939ms","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-24T04:36:02.83393Z","caller":"traceutil/trace.go:171","msg":"trace[653649763] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1892; }","duration":"281.960581ms","start":"2026-07-24T04:36:02.551956Z","end":"2026-07-24T04:36:02.833916Z","steps":["trace[653649763] 'agreement among raft nodes before linearized reading' (duration: 281.849559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.833838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.407564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:36:02.833884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.330326Z","time spent":"503.515666ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-24T04:36:02.834044Z","caller":"traceutil/trace.go:171","msg":"trace[1979366365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1892; }","duration":"433.685218ms","start":"2026-07-24T04:36:02.400336Z","end":"2026-07-24T04:36:02.834021Z","steps":["trace[1979366365] 'agreement among raft nodes before linearized reading' (duration: 432.561091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:02.834265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.400307Z","time spent":"433.901842ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T04:36:03.281291Z","caller":"traceutil/trace.go:171","msg":"trace[1772723094] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"448.488264ms","start":"2026-07-24T04:36:02.832779Z","end":"2026-07-24T04:36:03.281267Z","steps":["trace[1772723094] 'read index received' (duration: 448.20178ms)","trace[1772723094] 'applied index is now lower than readState.Index' (duration: 285.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:03.281398Z","caller":"traceutil/trace.go:171","msg":"trace[1018643760] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"470.11378ms","start":"2026-07-24T04:36:02.811247Z","end":"2026-07-24T04:36:03.281361Z","steps":["trace[1018643760] 'process raft request' (duration: 469.830036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.281674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.811218Z","time spent":"470.389595ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:36:03.281478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.919675ms","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-24T04:36:03.281833Z","caller":"traceutil/trace.go:171","msg":"trace[2088898135] 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:1893; }","duration":"453.345423ms","start":"2026-07-24T04:36:02.828469Z","end":"2026-07-24T04:36:03.281815Z","steps":["trace[2088898135] 'agreement among raft nodes before linearized reading' (duration: 452.911576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.281926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.828439Z","time spent":"453.440524ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:36:03.327432Z","caller":"traceutil/trace.go:171","msg":"trace[500579464] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"488.478952ms","start":"2026-07-24T04:36:02.838924Z","end":"2026-07-24T04:36:03.327403Z","steps":["trace[500579464] 'process raft request' (duration: 481.311348ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:03.327528Z","caller":"traceutil/trace.go:171","msg":"trace[1129217185] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"486.977578ms","start":"2026-07-24T04:36:02.840518Z","end":"2026-07-24T04:36:03.327496Z","steps":["trace[1129217185] 'process raft request' (duration: 486.726944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.327624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.838892Z","time spent":"488.621025ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:36:03.327743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.840497Z","time spent":"487.153781ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:03.327756Z","caller":"traceutil/trace.go:171","msg":"trace[1507313221] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"419.019511ms","start":"2026-07-24T04:36:02.908649Z","end":"2026-07-24T04:36:03.327669Z","steps":["trace[1507313221] 'process raft request' (duration: 418.749926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.327851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:02.908605Z","time spent":"419.196794ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:03.328202Z","caller":"traceutil/trace.go:171","msg":"trace[936141573] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"294.216076ms","start":"2026-07-24T04:36:03.033968Z","end":"2026-07-24T04:36:03.328184Z","steps":["trace[936141573] 'process raft request' (duration: 293.521595ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:03.328248Z","caller":"traceutil/trace.go:171","msg":"trace[414229576] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"170.930298ms","start":"2026-07-24T04:36:03.157304Z","end":"2026-07-24T04:36:03.328234Z","steps":["trace[414229576] 'process raft request' (duration: 170.816736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.32844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.559977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:36:03.328476Z","caller":"traceutil/trace.go:171","msg":"trace[2023850039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1899; }","duration":"248.639208ms","start":"2026-07-24T04:36:03.079828Z","end":"2026-07-24T04:36:03.328467Z","steps":["trace[2023850039] 'agreement among raft nodes before linearized reading' (duration: 248.495425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:03.328813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.596118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c51fc571cd9fa8\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-24T04:36:03.329045Z","caller":"traceutil/trace.go:171","msg":"trace[1934708094] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c51fc571cd9fa8; range_end:; response_count:1; response_revision:1899; }","duration":"243.77279ms","start":"2026-07-24T04:36:03.085116Z","end":"2026-07-24T04:36:03.328889Z","steps":["trace[1934708094] 'agreement among raft nodes before linearized reading' (duration: 243.202261ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:09.737893Z","caller":"traceutil/trace.go:171","msg":"trace[1512102938] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2051; }","duration":"210.966189ms","start":"2026-07-24T04:36:09.526897Z","end":"2026-07-24T04:36:09.737864Z","steps":["trace[1512102938] 'read index received' (duration: 210.645164ms)","trace[1512102938] 'applied index is now lower than readState.Index' (duration: 319.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:09.738033Z","caller":"traceutil/trace.go:171","msg":"trace[1048481321] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"215.190304ms","start":"2026-07-24T04:36:09.522825Z","end":"2026-07-24T04:36:09.738015Z","steps":["trace[1048481321] 'process raft request' (duration: 214.796618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:09.738325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.405708ms","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-24T04:36:09.738325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.407135ms","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-24T04:36:09.738394Z","caller":"traceutil/trace.go:171","msg":"trace[1141939864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1967; }","duration":"211.551989ms","start":"2026-07-24T04:36:09.526828Z","end":"2026-07-24T04:36:09.73838Z","steps":["trace[1141939864] 'agreement among raft nodes before linearized reading' (duration: 211.310064ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:09.738388Z","caller":"traceutil/trace.go:171","msg":"trace[8658048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1967; }","duration":"203.503892ms","start":"2026-07-24T04:36:09.534867Z","end":"2026-07-24T04:36:09.738371Z","steps":["trace[8658048] 'agreement among raft nodes before linearized reading' (duration: 203.240967ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:11.491678Z","caller":"traceutil/trace.go:171","msg":"trace[760779395] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2058; }","duration":"125.16754ms","start":"2026-07-24T04:36:11.366484Z","end":"2026-07-24T04:36:11.491651Z","steps":["trace[760779395] 'read index received' (duration: 124.982757ms)","trace[760779395] 'applied index is now lower than readState.Index' (duration: 183.483µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:11.491757Z","caller":"traceutil/trace.go:171","msg":"trace[590429198] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"133.271837ms","start":"2026-07-24T04:36:11.35844Z","end":"2026-07-24T04:36:11.491712Z","steps":["trace[590429198] 'process raft request' (duration: 133.031773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:11.492046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.097899ms","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-24T04:36:11.492088Z","caller":"traceutil/trace.go:171","msg":"trace[1483257608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1974; }","duration":"118.210551ms","start":"2026-07-24T04:36:11.373868Z","end":"2026-07-24T04:36:11.492078Z","steps":["trace[1483257608] 'agreement among raft nodes before linearized reading' (duration: 117.959466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:11.492031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.515404ms","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-24T04:36:11.492144Z","caller":"traceutil/trace.go:171","msg":"trace[477182759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1974; }","duration":"125.703638ms","start":"2026-07-24T04:36:11.366417Z","end":"2026-07-24T04:36:11.492121Z","steps":["trace[477182759] 'agreement among raft nodes before linearized reading' (duration: 125.356642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.027569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870955039608615,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:36:16.522427Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016847803s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:36:16.523Z","caller":"traceutil/trace.go:171","msg":"trace[1459691530] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"1.017537713s","start":"2026-07-24T04:36:15.505415Z","end":"2026-07-24T04:36:16.522953Z","steps":["trace[1459691530] 'process raft request' (duration: 1.017350989s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.523215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.505385Z","time spent":"1.017724875s","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:36:16.73679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.239812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:36:16.736981Z","caller":"traceutil/trace.go:171","msg":"trace[1097695495] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2096; }","duration":"1.20986413s","start":"2026-07-24T04:36:15.527034Z","end":"2026-07-24T04:36:16.736898Z","steps":["trace[1097695495] 'read index received' (duration: 995.680463ms)","trace[1097695495] 'applied index is now lower than readState.Index' (duration: 214.181887ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:16.737131Z","caller":"traceutil/trace.go:171","msg":"trace[1060914922] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"962.320168ms","start":"2026-07-24T04:36:15.774759Z","end":"2026-07-24T04:36:16.737079Z","steps":["trace[1060914922] 'process raft request' (duration: 861.64819ms)","trace[1060914922] 'compare' (duration: 100.06165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:16.737271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205803578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T04:36:16.737271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.898807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:36:16.737383Z","caller":"traceutil/trace.go:171","msg":"trace[1721657795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2012; }","duration":"658.04575ms","start":"2026-07-24T04:36:16.079317Z","end":"2026-07-24T04:36:16.737363Z","steps":["trace[1721657795] 'agreement among raft nodes before linearized reading' (duration: 657.827337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.737414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.314881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T04:36:16.73741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.774732Z","time spent":"962.548722ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:16.737447Z","caller":"traceutil/trace.go:171","msg":"trace[511387511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2012; }","duration":"955.378651ms","start":"2026-07-24T04:36:15.782059Z","end":"2026-07-24T04:36:16.737438Z","steps":["trace[511387511] 'agreement among raft nodes before linearized reading' (duration: 955.28234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.737498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.782042Z","time spent":"955.446622ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:36:16.737501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.728257ms","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-24T04:36:16.737579Z","caller":"traceutil/trace.go:171","msg":"trace[1746790853] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2012; }","duration":"783.828128ms","start":"2026-07-24T04:36:15.953727Z","end":"2026-07-24T04:36:16.737555Z","steps":["trace[1746790853] 'agreement among raft nodes before linearized reading' (duration: 783.603615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.737433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:16.079298Z","time spent":"658.126871ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:36:16.737637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.953712Z","time spent":"783.914319ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-24T04:36:16.737726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.420751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-07-24T04:36:16.73777Z","caller":"traceutil/trace.go:171","msg":"trace[1551762666] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2012; }","duration":"588.511693ms","start":"2026-07-24T04:36:16.14925Z","end":"2026-07-24T04:36:16.737762Z","steps":["trace[1551762666] 'agreement among raft nodes before linearized reading' (duration: 588.387811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.737252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210194095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T04:36:16.737794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.516443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:36:16.737589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.986306ms","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-24T04:36:16.737868Z","caller":"traceutil/trace.go:171","msg":"trace[2095341209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2012; }","duration":"1.210864586s","start":"2026-07-24T04:36:15.526989Z","end":"2026-07-24T04:36:16.737854Z","steps":["trace[2095341209] 'agreement among raft nodes before linearized reading' (duration: 1.210084223s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:16.738036Z","caller":"traceutil/trace.go:171","msg":"trace[470584428] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2012; }","duration":"176.801989ms","start":"2026-07-24T04:36:16.561161Z","end":"2026-07-24T04:36:16.737963Z","steps":["trace[470584428] 'agreement among raft nodes before linearized reading' (duration: 176.450513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.737295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.351118ms","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-24T04:36:16.738267Z","caller":"traceutil/trace.go:171","msg":"trace[1862956802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2012; }","duration":"796.358792ms","start":"2026-07-24T04:36:15.941889Z","end":"2026-07-24T04:36:16.738248Z","steps":["trace[1862956802] 'agreement among raft nodes before linearized reading' (duration: 795.302607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.738185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.526968Z","time spent":"1.21119682s","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:36:16.738331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.941871Z","time spent":"796.440583ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:36:16.737315Z","caller":"traceutil/trace.go:171","msg":"trace[2065965116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"1.205856508s","start":"2026-07-24T04:36:15.531446Z","end":"2026-07-24T04:36:16.737302Z","steps":["trace[2065965116] 'agreement among raft nodes before linearized reading' (duration: 1.205646824s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.73867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.531441Z","time spent":"1.207217809s","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:36:16.737798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:16.149209Z","time spent":"588.583143ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12010,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-24T04:36:16.739651Z","caller":"traceutil/trace.go:171","msg":"trace[578262483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2012; }","duration":"957.499894ms","start":"2026-07-24T04:36:15.780511Z","end":"2026-07-24T04:36:16.738011Z","steps":["trace[578262483] 'agreement among raft nodes before linearized reading' (duration: 956.946045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:16.739936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:15.780491Z","time spent":"959.423504ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T04:36:38.042607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.556628ms","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-24T04:36:38.042759Z","caller":"traceutil/trace.go:171","msg":"trace[555265737] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"143.756001ms","start":"2026-07-24T04:36:37.898971Z","end":"2026-07-24T04:36:38.042727Z","steps":["trace[555265737] 'range keys from in-memory index tree' (duration: 143.356156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:39.674199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.036326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:36:39.674324Z","caller":"traceutil/trace.go:171","msg":"trace[276982847] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"640.054525ms","start":"2026-07-24T04:36:39.034247Z","end":"2026-07-24T04:36:39.674302Z","steps":["trace[276982847] 'process raft request' (duration: 409.783625ms)","trace[276982847] 'compare' (duration: 229.896464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:39.674386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:39.034226Z","time spent":"640.131425ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:36:39.942025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.226324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:36:39.942496Z","caller":"traceutil/trace.go:171","msg":"trace[1105872196] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"209.302508ms","start":"2026-07-24T04:36:39.733172Z","end":"2026-07-24T04:36:39.942474Z","steps":["trace[1105872196] 'process raft request' (duration: 208.970953ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:39.94273Z","caller":"traceutil/trace.go:171","msg":"trace[1895620887] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"906.747125ms","start":"2026-07-24T04:36:39.035961Z","end":"2026-07-24T04:36:39.942708Z","steps":["trace[1895620887] 'process raft request' (duration: 746.727929ms)","trace[1895620887] 'compare' (duration: 158.961441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:39.94282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:39.035945Z","time spent":"906.830436ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:39.94304Z","caller":"traceutil/trace.go:171","msg":"trace[1426418797] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2237; }","duration":"888.310942ms","start":"2026-07-24T04:36:39.054443Z","end":"2026-07-24T04:36:39.942754Z","steps":["trace[1426418797] 'read index received' (duration: 389.602316ms)","trace[1426418797] 'applied index is now lower than readState.Index' (duration: 498.700796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:36:39.943161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.698488ms","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-24T04:36:39.943743Z","caller":"traceutil/trace.go:171","msg":"trace[1915282231] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"889.266046ms","start":"2026-07-24T04:36:39.054438Z","end":"2026-07-24T04:36:39.943704Z","steps":["trace[1915282231] 'agreement among raft nodes before linearized reading' (duration: 888.515605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:39.943575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.367435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:36:39.944012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:39.054427Z","time spent":"889.460589ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-24T04:36:39.94401Z","caller":"traceutil/trace.go:171","msg":"trace[1036647127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2148; }","duration":"864.806052ms","start":"2026-07-24T04:36:39.079167Z","end":"2026-07-24T04:36:39.943973Z","steps":["trace[1036647127] 'agreement among raft nodes before linearized reading' (duration: 864.366085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:39.94427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:36:39.079156Z","time spent":"865.041815ms","remote":"127.0.0.1:59588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:37:37.314482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.770219ms","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-24T04:37:37.314623Z","caller":"traceutil/trace.go:171","msg":"trace[36097748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2499; }","duration":"163.972972ms","start":"2026-07-24T04:37:37.150622Z","end":"2026-07-24T04:37:37.314595Z","steps":["trace[36097748] 'range keys from in-memory index tree' (duration: 163.422464ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:46.351578Z","caller":"traceutil/trace.go:171","msg":"trace[351406222] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2711; }","duration":"256.707051ms","start":"2026-07-24T04:37:46.094842Z","end":"2026-07-24T04:37:46.351549Z","steps":["trace[351406222] 'read index received' (duration: 256.512784ms)","trace[351406222] 'applied index is now lower than readState.Index' (duration: 192.997µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:46.351662Z","caller":"traceutil/trace.go:171","msg":"trace[667165066] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"429.19249ms","start":"2026-07-24T04:37:45.922413Z","end":"2026-07-24T04:37:46.351606Z","steps":["trace[667165066] 'process raft request' (duration: 428.940341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:46.351852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.97997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:37:46.351892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:45.922389Z","time spent":"429.392936ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:37:46.351938Z","caller":"traceutil/trace.go:171","msg":"trace[41759213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2605; }","duration":"257.111376ms","start":"2026-07-24T04:37:46.094805Z","end":"2026-07-24T04:37:46.351916Z","steps":["trace[41759213] 'agreement among raft nodes before linearized reading' (duration: 256.909518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:46.384536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.121543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-24T04:37:46.384671Z","caller":"traceutil/trace.go:171","msg":"trace[866346733] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2606; }","duration":"150.30508ms","start":"2026-07-24T04:37:46.234353Z","end":"2026-07-24T04:37:46.384658Z","steps":["trace[866346733] 'agreement among raft nodes before linearized reading' (duration: 149.845775ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:46.385001Z","caller":"traceutil/trace.go:171","msg":"trace[1422601150] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"190.463821ms","start":"2026-07-24T04:37:46.194501Z","end":"2026-07-24T04:37:46.384965Z","steps":["trace[1422601150] 'process raft request' (duration: 189.360813ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:38:18.762018Z","caller":"traceutil/trace.go:171","msg":"trace[1798386267] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"151.017469ms","start":"2026-07-24T04:38:18.610972Z","end":"2026-07-24T04:38:18.76199Z","steps":["trace[1798386267] 'process raft request' (duration: 150.667188ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:16.487835Z","caller":"traceutil/trace.go:171","msg":"trace[863539347] transaction","detail":"{read_only:false; response_revision:3489; number_of_response:1; }","duration":"293.038656ms","start":"2026-07-24T04:40:16.194773Z","end":"2026-07-24T04:40:16.487812Z","steps":["trace[863539347] 'process raft request' (duration: 206.858736ms)","trace[863539347] 'compare' (duration: 86.096047ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:16.542339Z","caller":"traceutil/trace.go:171","msg":"trace[2882576] linearizableReadLoop","detail":"{readStateIndex:3632; appliedIndex:3630; }","duration":"340.765319ms","start":"2026-07-24T04:40:16.201554Z","end":"2026-07-24T04:40:16.542319Z","steps":["trace[2882576] 'read index received' (duration: 200.105482ms)","trace[2882576] 'applied index is now lower than readState.Index' (duration: 140.659197ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:16.542446Z","caller":"traceutil/trace.go:171","msg":"trace[585721492] transaction","detail":"{read_only:false; response_revision:3490; number_of_response:1; }","duration":"347.619516ms","start":"2026-07-24T04:40:16.194804Z","end":"2026-07-24T04:40:16.542424Z","steps":["trace[585721492] 'process raft request' (duration: 347.413781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:16.542559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:16.194789Z","time spent":"347.706228ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:40:16.542494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.920493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:16.542616Z","caller":"traceutil/trace.go:171","msg":"trace[1706113380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:3490; }","duration":"341.081296ms","start":"2026-07-24T04:40:16.201524Z","end":"2026-07-24T04:40:16.542605Z","steps":["trace[1706113380] 'agreement among raft nodes before linearized reading' (duration: 340.894502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:16.542756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.238806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-24T04:40:16.542753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.304634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:16.542791Z","caller":"traceutil/trace.go:171","msg":"trace[775959266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3490; }","duration":"243.296319ms","start":"2026-07-24T04:40:16.299485Z","end":"2026-07-24T04:40:16.542781Z","steps":["trace[775959266] 'agreement among raft nodes before linearized reading' (duration: 243.170837ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:16.542805Z","caller":"traceutil/trace.go:171","msg":"trace[273004751] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3490; }","duration":"157.376895ms","start":"2026-07-24T04:40:16.385414Z","end":"2026-07-24T04:40:16.54279Z","steps":["trace[273004751] 'agreement among raft nodes before linearized reading' (duration: 157.297884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:16.542795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.421628ms","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-24T04:40:16.542778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.530332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:16.542862Z","caller":"traceutil/trace.go:171","msg":"trace[165453872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3490; }","duration":"241.491639ms","start":"2026-07-24T04:40:16.301353Z","end":"2026-07-24T04:40:16.542844Z","steps":["trace[165453872] 'agreement among raft nodes before linearized reading' (duration: 241.289005ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:16.542901Z","caller":"traceutil/trace.go:171","msg":"trace[1383997750] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:3490; }","duration":"304.712865ms","start":"2026-07-24T04:40:16.238161Z","end":"2026-07-24T04:40:16.542874Z","steps":["trace[1383997750] 'agreement among raft nodes before linearized reading' (duration: 304.429049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:16.542987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:16.238154Z","time spent":"304.820538ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:40:16.543151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:16.201512Z","time spent":"341.619327ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:40:17.262144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.536093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:17.262347Z","caller":"traceutil/trace.go:171","msg":"trace[88632505] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"279.560326ms","start":"2026-07-24T04:40:16.982734Z","end":"2026-07-24T04:40:17.262295Z","steps":["trace[88632505] 'process raft request' (duration: 91.781936ms)","trace[88632505] 'compare' (duration: 187.388781ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:17.262252Z","caller":"traceutil/trace.go:171","msg":"trace[196470071] linearizableReadLoop","detail":"{readStateIndex:3636; appliedIndex:3635; }","duration":"252.77723ms","start":"2026-07-24T04:40:17.009457Z","end":"2026-07-24T04:40:17.262234Z","steps":["trace[196470071] 'read index received' (duration: 65.00687ms)","trace[196470071] 'applied index is now lower than readState.Index' (duration: 187.76909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:17.262662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.185329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-07-24T04:40:17.262739Z","caller":"traceutil/trace.go:171","msg":"trace[1826916448] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3494; }","duration":"253.301201ms","start":"2026-07-24T04:40:17.009419Z","end":"2026-07-24T04:40:17.26272Z","steps":["trace[1826916448] 'agreement among raft nodes before linearized reading' (duration: 252.974355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:17.262746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.25394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-07-24T04:40:17.263006Z","caller":"traceutil/trace.go:171","msg":"trace[2020616620] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3494; }","duration":"253.538596ms","start":"2026-07-24T04:40:17.009445Z","end":"2026-07-24T04:40:17.262984Z","steps":["trace[2020616620] 'agreement among raft nodes before linearized reading' (duration: 253.076296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:17.307039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.730789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:17.30716Z","caller":"traceutil/trace.go:171","msg":"trace[311158685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3495; }","duration":"223.899552ms","start":"2026-07-24T04:40:17.083233Z","end":"2026-07-24T04:40:17.307133Z","steps":["trace[311158685] 'agreement among raft nodes before linearized reading' (duration: 223.663436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:17.480863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.502112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:17.481784Z","caller":"traceutil/trace.go:171","msg":"trace[1748794276] transaction","detail":"{read_only:false; response_revision:3497; number_of_response:1; }","duration":"149.960779ms","start":"2026-07-24T04:40:17.331769Z","end":"2026-07-24T04:40:17.48173Z","steps":["trace[1748794276] 'process raft request' (duration: 41.513258ms)","trace[1748794276] 'compare' (duration: 107.268157ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:17.727405Z","caller":"traceutil/trace.go:171","msg":"trace[1825922337] transaction","detail":"{read_only:false; response_revision:3499; number_of_response:1; }","duration":"121.806037ms","start":"2026-07-24T04:40:17.605572Z","end":"2026-07-24T04:40:17.727378Z","steps":["trace[1825922337] 'process raft request' (duration: 121.510081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:53.26379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.487685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:53.264189Z","caller":"traceutil/trace.go:171","msg":"trace[115737542] linearizableReadLoop","detail":"{readStateIndex:4717; appliedIndex:4716; }","duration":"110.914426ms","start":"2026-07-24T04:41:53.153253Z","end":"2026-07-24T04:41:53.264168Z","steps":["trace[115737542] 'read index received' (duration: 4.88677ms)","trace[115737542] 'applied index is now lower than readState.Index' (duration: 106.025706ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:53.264233Z","caller":"traceutil/trace.go:171","msg":"trace[363622246] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"134.218654ms","start":"2026-07-24T04:41:53.129992Z","end":"2026-07-24T04:41:53.264211Z","steps":["trace[363622246] 'process raft request' (duration: 28.190679ms)","trace[363622246] 'compare' (duration: 105.387714ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:53.271717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.332662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1433"} {"level":"info","ts":"2026-07-24T04:41:53.271769Z","caller":"traceutil/trace.go:171","msg":"trace[94105425] range","detail":"{range_begin:/registry/clusterrolebindings/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4536; }","duration":"118.557357ms","start":"2026-07-24T04:41:53.1532Z","end":"2026-07-24T04:41:53.271757Z","steps":["trace[94105425] 'agreement among raft nodes before linearized reading' (duration: 111.070978ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:53.730815Z","caller":"traceutil/trace.go:171","msg":"trace[1422325131] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"102.446989ms","start":"2026-07-24T04:41:53.628344Z","end":"2026-07-24T04:41:53.730791Z","steps":["trace[1422325131] 'process raft request' (duration: 52.309115ms)","trace[1422325131] 'compare' (duration: 49.891ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.006014Z","caller":"traceutil/trace.go:171","msg":"trace[575540203] linearizableReadLoop","detail":"{readStateIndex:4919; appliedIndex:4918; }","duration":"478.735071ms","start":"2026-07-24T04:42:02.527258Z","end":"2026-07-24T04:42:03.005994Z","steps":["trace[575540203] 'read index received' (duration: 478.377714ms)","trace[575540203] 'applied index is now lower than readState.Index' (duration: 356.457µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.00609Z","caller":"traceutil/trace.go:171","msg":"trace[196716200] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"582.285102ms","start":"2026-07-24T04:42:02.423768Z","end":"2026-07-24T04:42:03.006053Z","steps":["trace[196716200] 'process raft request' (duration: 581.946126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.006245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:02.423748Z","time spent":"582.415404ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:42:03.006244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.959135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:42:03.006309Z","caller":"traceutil/trace.go:171","msg":"trace[403223029] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4731; }","duration":"479.042606ms","start":"2026-07-24T04:42:02.527253Z","end":"2026-07-24T04:42:03.006296Z","steps":["trace[403223029] 'agreement among raft nodes before linearized reading' (duration: 478.890524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.006345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:02.52721Z","time spent":"479.125008ms","remote":"127.0.0.1:60058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":21,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:42:03.006753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.136057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:42:03.006799Z","caller":"traceutil/trace.go:171","msg":"trace[1476159498] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4731; }","duration":"141.217489ms","start":"2026-07-24T04:42:02.865566Z","end":"2026-07-24T04:42:03.006784Z","steps":["trace[1476159498] 'agreement among raft nodes before linearized reading' (duration: 141.139667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.007129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.036798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T04:42:03.007165Z","caller":"traceutil/trace.go:171","msg":"trace[722186678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4731; }","duration":"311.11418ms","start":"2026-07-24T04:42:02.696041Z","end":"2026-07-24T04:42:03.007156Z","steps":["trace[722186678] 'agreement among raft nodes before linearized reading' (duration: 310.986428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.007193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:02.696017Z","time spent":"311.16842ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:42:03.011015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.702574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-07-24T04:42:03.011082Z","caller":"traceutil/trace.go:171","msg":"trace[258259784] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4731; }","duration":"369.337434ms","start":"2026-07-24T04:42:02.641729Z","end":"2026-07-24T04:42:03.011067Z","steps":["trace[258259784] 'agreement among raft nodes before linearized reading' (duration: 364.820512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.011134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:02.64167Z","time spent":"369.439865ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207440,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-07-24T04:42:03.126937Z","caller":"traceutil/trace.go:171","msg":"trace[1078195476] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"111.2909ms","start":"2026-07-24T04:42:03.015625Z","end":"2026-07-24T04:42:03.126916Z","steps":["trace[1078195476] 'process raft request' (duration: 102.55442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.418874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-24T04:42:03.419113Z","caller":"traceutil/trace.go:171","msg":"trace[562305214] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4926; }","duration":"204.649623ms","start":"2026-07-24T04:42:03.214385Z","end":"2026-07-24T04:42:03.419034Z","steps":["trace[562305214] 'read index received' (duration: 45.485089ms)","trace[562305214] 'applied index is now lower than readState.Index' (duration: 159.163604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:03.419713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.032297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-07-24T04:42:03.419783Z","caller":"traceutil/trace.go:171","msg":"trace[1292512034] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4743; }","duration":"229.146119ms","start":"2026-07-24T04:42:03.190622Z","end":"2026-07-24T04:42:03.419768Z","steps":["trace[1292512034] 'agreement among raft nodes before linearized reading' (duration: 228.620799ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.420125Z","caller":"traceutil/trace.go:171","msg":"trace[2055441021] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"188.06871ms","start":"2026-07-24T04:42:03.232047Z","end":"2026-07-24T04:42:03.420116Z","steps":["trace[2055441021] 'process raft request' (duration: 186.92635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.470781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.392188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T04:42:03.470863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.395491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-76ac436e-fc7d-468b-982f-cc6b77aa24ee\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-24T04:42:03.47085Z","caller":"traceutil/trace.go:171","msg":"trace[1151445176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4744; }","duration":"170.51521ms","start":"2026-07-24T04:42:03.300317Z","end":"2026-07-24T04:42:03.470832Z","steps":["trace[1151445176] 'agreement among raft nodes before linearized reading' (duration: 119.827305ms)","trace[1151445176] 'range keys from in-memory index tree' (duration: 50.520372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:03.471029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.351595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-24T04:42:03.470931Z","caller":"traceutil/trace.go:171","msg":"trace[600737955] range","detail":"{range_begin:/registry/persistentvolumes/pvc-76ac436e-fc7d-468b-982f-cc6b77aa24ee; range_end:; response_count:1; response_revision:4744; }","duration":"253.490683ms","start":"2026-07-24T04:42:03.217424Z","end":"2026-07-24T04:42:03.470915Z","steps":["trace[600737955] 'agreement among raft nodes before linearized reading' (duration: 202.702906ms)","trace[600737955] 'range keys from in-memory index tree' (duration: 50.546813ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.471103Z","caller":"traceutil/trace.go:171","msg":"trace[1299680] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4744; }","duration":"279.451827ms","start":"2026-07-24T04:42:03.191632Z","end":"2026-07-24T04:42:03.471083Z","steps":["trace[1299680] 'agreement among raft nodes before linearized reading' (duration: 228.029409ms)","trace[1299680] 'range keys from in-memory index tree' (duration: 50.99461ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.617635Z","caller":"traceutil/trace.go:171","msg":"trace[1755465058] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"143.220642ms","start":"2026-07-24T04:42:03.47439Z","end":"2026-07-24T04:42:03.617611Z","steps":["trace[1755465058] 'read index received' (duration: 143.09514ms)","trace[1755465058] 'applied index is now lower than readState.Index' (duration: 124.392µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.617747Z","caller":"traceutil/trace.go:171","msg":"trace[1818543957] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"185.120617ms","start":"2026-07-24T04:42:03.432588Z","end":"2026-07-24T04:42:03.617709Z","steps":["trace[1818543957] 'process raft request' (duration: 184.868591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:03.61782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.411076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-24T04:42:03.617866Z","caller":"traceutil/trace.go:171","msg":"trace[1960695737] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4745; }","duration":"143.487137ms","start":"2026-07-24T04:42:03.474364Z","end":"2026-07-24T04:42:03.617851Z","steps":["trace[1960695737] 'agreement among raft nodes before linearized reading' (duration: 143.338285ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.746068Z","caller":"traceutil/trace.go:171","msg":"trace[1386495079] linearizableReadLoop","detail":"{readStateIndex:4936; appliedIndex:4935; }","duration":"121.999315ms","start":"2026-07-24T04:42:03.624048Z","end":"2026-07-24T04:42:03.746047Z","steps":["trace[1386495079] 'read index received' (duration: 88.780289ms)","trace[1386495079] 'applied index is now lower than readState.Index' (duration: 33.217816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:03.746313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.204829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-24T04:42:03.746358Z","caller":"traceutil/trace.go:171","msg":"trace[635237611] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4746; }","duration":"122.30362ms","start":"2026-07-24T04:42:03.624042Z","end":"2026-07-24T04:42:03.746346Z","steps":["trace[635237611] 'agreement among raft nodes before linearized reading' (duration: 122.105507ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.746339Z","caller":"traceutil/trace.go:171","msg":"trace[415988278] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"271.411731ms","start":"2026-07-24T04:42:03.47482Z","end":"2026-07-24T04:42:03.746231Z","steps":["trace[415988278] 'process raft request' (duration: 237.993642ms)","trace[415988278] 'compare' (duration: 33.119124ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:03.798517Z","caller":"traceutil/trace.go:171","msg":"trace[2013920176] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"174.152847ms","start":"2026-07-24T04:42:03.624332Z","end":"2026-07-24T04:42:03.798485Z","steps":["trace[2013920176] 'process raft request' (duration: 173.914192ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.798749Z","caller":"traceutil/trace.go:171","msg":"trace[17286479] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"172.543177ms","start":"2026-07-24T04:42:03.626183Z","end":"2026-07-24T04:42:03.798726Z","steps":["trace[17286479] 'process raft request' (duration: 172.203981ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.79882Z","caller":"traceutil/trace.go:171","msg":"trace[313322489] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"118.40673ms","start":"2026-07-24T04:42:03.680403Z","end":"2026-07-24T04:42:03.79881Z","steps":["trace[313322489] 'process raft request' (duration: 118.111915ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.798912Z","caller":"traceutil/trace.go:171","msg":"trace[1453942514] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"172.259832ms","start":"2026-07-24T04:42:03.626642Z","end":"2026-07-24T04:42:03.798902Z","steps":["trace[1453942514] 'process raft request' (duration: 171.835755ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:03.798683Z","caller":"traceutil/trace.go:171","msg":"trace[907960515] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"172.404464ms","start":"2026-07-24T04:42:03.626248Z","end":"2026-07-24T04:42:03.798653Z","steps":["trace[907960515] 'process raft request' (duration: 172.178141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:06.30264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.520778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ecc85504-0872-45bc-9e9e-a4d52157ce64\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-07-24T04:42:06.302748Z","caller":"traceutil/trace.go:171","msg":"trace[387434078] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ecc85504-0872-45bc-9e9e-a4d52157ce64; range_end:; response_count:1; response_revision:4777; }","duration":"100.690461ms","start":"2026-07-24T04:42:06.202032Z","end":"2026-07-24T04:42:06.302723Z","steps":["trace[387434078] 'range keys from in-memory index tree' (duration: 100.147331ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:15.987175Z","caller":"traceutil/trace.go:171","msg":"trace[968871978] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"144.252419ms","start":"2026-07-24T04:42:15.842902Z","end":"2026-07-24T04:42:15.987154Z","steps":["trace[968871978] 'process raft request' (duration: 144.113437ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:15.994066Z","caller":"traceutil/trace.go:171","msg":"trace[1363139578] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"143.730331ms","start":"2026-07-24T04:42:15.850291Z","end":"2026-07-24T04:42:15.994021Z","steps":["trace[1363139578] 'process raft request' (duration: 143.582829ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:52.090661Z","caller":"traceutil/trace.go:171","msg":"trace[1582180487] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"108.571866ms","start":"2026-07-24T04:42:51.982057Z","end":"2026-07-24T04:42:52.090629Z","steps":["trace[1582180487] 'process raft request' (duration: 108.350492ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:52.245098Z","caller":"traceutil/trace.go:171","msg":"trace[851226777] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5445; }","duration":"101.251827ms","start":"2026-07-24T04:42:52.14383Z","end":"2026-07-24T04:42:52.245082Z","steps":["trace[851226777] 'read index received' (duration: 31.075682ms)","trace[851226777] 'applied index is now lower than readState.Index' (duration: 70.175325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:52.245263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.413291ms","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-24T04:42:52.245293Z","caller":"traceutil/trace.go:171","msg":"trace[541884111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5242; }","duration":"101.495842ms","start":"2026-07-24T04:42:52.143788Z","end":"2026-07-24T04:42:52.245284Z","steps":["trace[541884111] 'agreement among raft nodes before linearized reading' (duration: 101.3594ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:52.245302Z","caller":"traceutil/trace.go:171","msg":"trace[2002307771] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"105.709446ms","start":"2026-07-24T04:42:52.139568Z","end":"2026-07-24T04:42:52.245277Z","steps":["trace[2002307771] 'process raft request' (duration: 48.110329ms)","trace[2002307771] 'compare' (duration: 57.345982ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:24.5591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2813} {"level":"info","ts":"2026-07-24T04:43:24.669801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2813,"took":"109.073994ms","hash":1896369027,"current-db-size-bytes":54390784,"current-db-size":"54 MB","current-db-size-in-use-bytes":29143040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-24T04:43:24.669879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896369027,"revision":2813,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:46:01.163331Z","caller":"traceutil/trace.go:171","msg":"trace[816315758] transaction","detail":"{read_only:false; response_revision:6543; number_of_response:1; }","duration":"168.306473ms","start":"2026-07-24T04:46:00.995Z","end":"2026-07-24T04:46:01.163307Z","steps":["trace[816315758] 'process raft request' (duration: 168.109469ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:01.163587Z","caller":"traceutil/trace.go:171","msg":"trace[1492160068] transaction","detail":"{read_only:false; response_revision:6544; number_of_response:1; }","duration":"168.522865ms","start":"2026-07-24T04:46:00.995017Z","end":"2026-07-24T04:46:01.16354Z","steps":["trace[1492160068] 'process raft request' (duration: 168.241841ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:01.170396Z","caller":"traceutil/trace.go:171","msg":"trace[916909994] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"172.442309ms","start":"2026-07-24T04:46:00.99794Z","end":"2026-07-24T04:46:01.170382Z","steps":["trace[916909994] 'process raft request' (duration: 172.226925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:34.14872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870955039620231,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:46:34.191454Z","caller":"traceutil/trace.go:171","msg":"trace[1067357297] transaction","detail":"{read_only:false; response_revision:6762; number_of_response:1; }","duration":"869.054274ms","start":"2026-07-24T04:46:33.322374Z","end":"2026-07-24T04:46:34.191428Z","steps":["trace[1067357297] 'process raft request' (duration: 868.913249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:34.191597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:33.322352Z","time spent":"869.174469ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:46:34.194406Z","caller":"traceutil/trace.go:171","msg":"trace[1119609373] transaction","detail":"{read_only:false; response_revision:6763; number_of_response:1; }","duration":"870.904275ms","start":"2026-07-24T04:46:33.323489Z","end":"2026-07-24T04:46:34.194393Z","steps":["trace[1119609373] 'process raft request' (duration: 870.816722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:34.194478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:33.323477Z","time spent":"870.967418ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:46:34.194535Z","caller":"traceutil/trace.go:171","msg":"trace[372142515] linearizableReadLoop","detail":"{readStateIndex:7024; appliedIndex:7022; }","duration":"757.638541ms","start":"2026-07-24T04:46:33.436884Z","end":"2026-07-24T04:46:34.194522Z","steps":["trace[372142515] 'read index received' (duration: 754.725189ms)","trace[372142515] 'applied index is now lower than readState.Index' (duration: 2.912372ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:34.194599Z","caller":"traceutil/trace.go:171","msg":"trace[501391966] transaction","detail":"{read_only:false; response_revision:6764; number_of_response:1; }","duration":"870.680928ms","start":"2026-07-24T04:46:33.32391Z","end":"2026-07-24T04:46:34.194591Z","steps":["trace[501391966] 'process raft request' (duration: 870.453418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:34.194656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:33.323886Z","time spent":"870.733569ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:46:34.194792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.393263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T04:46:34.194851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.973304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T04:46:34.194894Z","caller":"traceutil/trace.go:171","msg":"trace[1034697680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6764; }","duration":"758.053147ms","start":"2026-07-24T04:46:33.436831Z","end":"2026-07-24T04:46:34.194884Z","steps":["trace[1034697680] 'agreement among raft nodes before linearized reading' (duration: 757.926172ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:34.194902Z","caller":"traceutil/trace.go:171","msg":"trace[2070525103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6764; }","duration":"756.58052ms","start":"2026-07-24T04:46:33.438299Z","end":"2026-07-24T04:46:34.194879Z","steps":["trace[2070525103] 'agreement among raft nodes before linearized reading' (duration: 756.314511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:34.194972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:33.438288Z","time spent":"756.666954ms","remote":"127.0.0.1:59870","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-24T04:46:34.194922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:33.436811Z","time spent":"758.103519ms","remote":"127.0.0.1:59870","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-24T04:46:50.957821Z","caller":"traceutil/trace.go:171","msg":"trace[1145897152] transaction","detail":"{read_only:false; response_revision:6873; number_of_response:1; }","duration":"136.993638ms","start":"2026-07-24T04:46:50.8208Z","end":"2026-07-24T04:46:50.957794Z","steps":["trace[1145897152] 'process raft request' (duration: 132.99801ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:24.568441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5473} {"level":"info","ts":"2026-07-24T04:48:24.707111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5473,"took":"136.143161ms","hash":55633339,"current-db-size-bytes":54390784,"current-db-size":"54 MB","current-db-size-in-use-bytes":18309120,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-24T04:48:24.707262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":55633339,"revision":5473,"compact-revision":2813}