{"level":"warn","ts":"2026-07-08T02:59:00.624636Z","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-08T02:59:00.624741Z","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-08T02:59:00.624824Z","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-08T02:59:00.624835Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-08T02:59:00.62486Z","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-08T02:59:00.626152Z","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-08T02:59:00.62634Z","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-08T02:59:00.634259Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.543788ms"} {"level":"info","ts":"2026-07-08T02:59:00.647766Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-08T02:59:00.64785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T02:59:00.647874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-08T02:59:00.647892Z","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-08T02:59:00.647907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-08T02:59:00.647947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-08T02:59:00.663419Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T02:59:00.664728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T02:59:00.665824Z","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-08T02:59:00.668643Z","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-08T02:59:00.668782Z","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-08T02:59:00.668879Z","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-08T02:59:00.672796Z","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-08T02:59:00.672831Z","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-08T02:59:00.672992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-08T02:59:00.673135Z","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-08T02:59:00.674123Z","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-08T02:59:00.674229Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T02:59:00.674258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T02:59:00.674386Z","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-08T02:59:00.674438Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T02:59:00.748546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T02:59:00.748604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T02:59:00.748623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-08T02:59:00.748639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-08T02:59:00.748646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T02:59:00.748658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-08T02:59:00.748672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T02:59:00.749514Z","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-08T02:59:00.749515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:59:00.74955Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:59:00.749608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:59:00.749843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T02:59:00.749879Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T02:59:00.750506Z","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-08T02:59:00.750609Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:59:00.750647Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:59:00.756161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-08T02:59:00.757678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T02:59:05.296003Z","caller":"traceutil/trace.go:171","msg":"trace[1394791503] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"168.679368ms","start":"2026-07-08T02:59:05.127292Z","end":"2026-07-08T02:59:05.295971Z","steps":["trace[1394791503] 'process raft request' (duration: 168.518834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:05.890115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.305155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:59:05.890362Z","caller":"traceutil/trace.go:171","msg":"trace[1286290981] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"197.12383ms","start":"2026-07-08T02:59:05.693171Z","end":"2026-07-08T02:59:05.890295Z","steps":["trace[1286290981] 'read index received' (duration: 33.124401ms)","trace[1286290981] 'applied index is now lower than readState.Index' (duration: 163.997939ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:59:05.890418Z","caller":"traceutil/trace.go:171","msg":"trace[1290542535] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"587.256532ms","start":"2026-07-08T02:59:05.303113Z","end":"2026-07-08T02:59:05.89037Z","steps":["trace[1290542535] 'process raft request' (duration: 423.235172ms)","trace[1290542535] 'compare' (duration: 163.163101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:05.890549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.369884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:59:05.890597Z","caller":"traceutil/trace.go:171","msg":"trace[920614649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:213; }","duration":"197.464207ms","start":"2026-07-08T02:59:05.693119Z","end":"2026-07-08T02:59:05.890583Z","steps":["trace[920614649] 'agreement among raft nodes before linearized reading' (duration: 197.315664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:05.890577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:05.303102Z","time spent":"587.405785ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T02:59:37.153325Z","caller":"traceutil/trace.go:171","msg":"trace[592467679] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"111.21708ms","start":"2026-07-08T02:59:37.042078Z","end":"2026-07-08T02:59:37.153295Z","steps":["trace[592467679] 'process raft request' (duration: 111.101698ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.157968Z","caller":"traceutil/trace.go:171","msg":"trace[1262661742] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"109.081154ms","start":"2026-07-08T02:59:37.048871Z","end":"2026-07-08T02:59:37.157953Z","steps":["trace[1262661742] 'process raft request' (duration: 108.493991ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.158055Z","caller":"traceutil/trace.go:171","msg":"trace[462013487] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"109.127944ms","start":"2026-07-08T02:59:37.048922Z","end":"2026-07-08T02:59:37.15805Z","steps":["trace[462013487] 'process raft request' (duration: 108.517801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.158112Z","caller":"traceutil/trace.go:171","msg":"trace[1952275011] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"102.861827ms","start":"2026-07-08T02:59:37.055246Z","end":"2026-07-08T02:59:37.158108Z","steps":["trace[1952275011] 'process raft request' (duration: 102.215073ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.158174Z","caller":"traceutil/trace.go:171","msg":"trace[161172971] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"101.389264ms","start":"2026-07-08T02:59:37.05678Z","end":"2026-07-08T02:59:37.158169Z","steps":["trace[161172971] 'process raft request' (duration: 100.70436ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:43.294663Z","caller":"traceutil/trace.go:171","msg":"trace[34548792] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"177.535499ms","start":"2026-07-08T02:59:43.1171Z","end":"2026-07-08T02:59:43.294635Z","steps":["trace[34548792] 'process raft request' (duration: 177.320135ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:43.294671Z","caller":"traceutil/trace.go:171","msg":"trace[1621841299] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"111.693589ms","start":"2026-07-08T02:59:43.182938Z","end":"2026-07-08T02:59:43.294632Z","steps":["trace[1621841299] 'read index received' (duration: 111.678108ms)","trace[1621841299] 'applied index is now lower than readState.Index' (duration: 13.001µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:43.294969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.006896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:59:43.295085Z","caller":"traceutil/trace.go:171","msg":"trace[2135387206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"112.183761ms","start":"2026-07-08T02:59:43.182879Z","end":"2026-07-08T02:59:43.295062Z","steps":["trace[2135387206] 'agreement among raft nodes before linearized reading' (duration: 111.972436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:43.842911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.214895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:59:43.843016Z","caller":"traceutil/trace.go:171","msg":"trace[91232059] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"385.635862ms","start":"2026-07-08T02:59:43.457349Z","end":"2026-07-08T02:59:43.842985Z","steps":["trace[91232059] 'read index received' (duration: 181.882575ms)","trace[91232059] 'applied index is now lower than readState.Index' (duration: 203.750557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:43.843064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.718874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z6qxt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-08T02:59:43.843078Z","caller":"traceutil/trace.go:171","msg":"trace[1318875618] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z6qxt; range_end:; response_count:1; response_revision:588; }","duration":"385.774805ms","start":"2026-07-08T02:59:43.457298Z","end":"2026-07-08T02:59:43.843073Z","steps":["trace[1318875618] 'agreement among raft nodes before linearized reading' (duration: 385.735934ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:43.843054Z","caller":"traceutil/trace.go:171","msg":"trace[631421931] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"581.864393ms","start":"2026-07-08T02:59:43.261159Z","end":"2026-07-08T02:59:43.843024Z","steps":["trace[631421931] 'process raft request' (duration: 378.353821ms)","trace[631421931] 'compare' (duration: 202.911109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:43.843099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:43.457278Z","time spent":"385.816786ms","remote":"127.0.0.1:48912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-z6qxt\" "} {"level":"warn","ts":"2026-07-08T02:59:43.843208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:43.261107Z","time spent":"582.011406ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:59:59.457755Z","caller":"traceutil/trace.go:171","msg":"trace[1385689109] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"308.913489ms","start":"2026-07-08T02:59:59.148797Z","end":"2026-07-08T02:59:59.457711Z","steps":["trace[1385689109] 'process raft request' (duration: 243.400676ms)","trace[1385689109] 'compare' (duration: 65.146004ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:59:59.457747Z","caller":"traceutil/trace.go:171","msg":"trace[566801015] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"289.662802ms","start":"2026-07-08T02:59:59.168048Z","end":"2026-07-08T02:59:59.457711Z","steps":["trace[566801015] 'read index received' (duration: 224.161109ms)","trace[566801015] 'applied index is now lower than readState.Index' (duration: 65.498543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:59.458278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:59.148764Z","time spent":"309.08377ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:59:59.464509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.908286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T02:59:59.464553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.494323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-08T02:59:59.464598Z","caller":"traceutil/trace.go:171","msg":"trace[715602781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"296.573315ms","start":"2026-07-08T02:59:59.168014Z","end":"2026-07-08T02:59:59.464588Z","steps":["trace[715602781] 'agreement among raft nodes before linearized reading' (duration: 289.801134ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:59.464592Z","caller":"traceutil/trace.go:171","msg":"trace[1934518592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"281.015559ms","start":"2026-07-08T02:59:59.183557Z","end":"2026-07-08T02:59:59.464573Z","steps":["trace[1934518592] 'agreement among raft nodes before linearized reading' (duration: 280.897856ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:01:24.806596Z","caller":"traceutil/trace.go:171","msg":"trace[423302159] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"100.839988ms","start":"2026-07-08T03:01:24.705726Z","end":"2026-07-08T03:01:24.806566Z","steps":["trace[423302159] 'read index received' (duration: 100.622091ms)","trace[423302159] 'applied index is now lower than readState.Index' (duration: 212.826µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:01:24.806705Z","caller":"traceutil/trace.go:171","msg":"trace[360951259] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"128.114367ms","start":"2026-07-08T03:01:24.678551Z","end":"2026-07-08T03:01:24.806665Z","steps":["trace[360951259] 'process raft request' (duration: 127.820179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:01:24.806892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.126695ms","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-08T03:01:24.806945Z","caller":"traceutil/trace.go:171","msg":"trace[2115432057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"101.272008ms","start":"2026-07-08T03:01:24.705655Z","end":"2026-07-08T03:01:24.806927Z","steps":["trace[2115432057] 'agreement among raft nodes before linearized reading' (duration: 101.062613ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:01:25.19992Z","caller":"traceutil/trace.go:171","msg":"trace[752862667] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"182.770133ms","start":"2026-07-08T03:01:25.01711Z","end":"2026-07-08T03:01:25.19988Z","steps":["trace[752862667] 'read index received' (duration: 182.490957ms)","trace[752862667] 'applied index is now lower than readState.Index' (duration: 277.616µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:01:25.200036Z","caller":"traceutil/trace.go:171","msg":"trace[1547351276] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"191.630257ms","start":"2026-07-08T03:01:25.008373Z","end":"2026-07-08T03:01:25.200003Z","steps":["trace[1547351276] 'process raft request' (duration: 191.289699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:01:25.200268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.126062ms","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-08T03:01:25.200372Z","caller":"traceutil/trace.go:171","msg":"trace[1423132487] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"183.307855ms","start":"2026-07-08T03:01:25.017033Z","end":"2026-07-08T03:01:25.200341Z","steps":["trace[1423132487] 'agreement among raft nodes before linearized reading' (duration: 183.004408ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:01:38.155692Z","caller":"traceutil/trace.go:171","msg":"trace[1674650842] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"179.398161ms","start":"2026-07-08T03:01:37.97625Z","end":"2026-07-08T03:01:38.155648Z","steps":["trace[1674650842] 'process raft request' (duration: 175.873887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:01:38.155902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.87928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T03:01:38.155966Z","caller":"traceutil/trace.go:171","msg":"trace[179669073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1975; }","duration":"171.986783ms","start":"2026-07-08T03:01:37.983966Z","end":"2026-07-08T03:01:38.155953Z","steps":["trace[179669073] 'agreement among raft nodes before linearized reading' (duration: 171.748338ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:01:38.155671Z","caller":"traceutil/trace.go:171","msg":"trace[1970936801] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"171.587074ms","start":"2026-07-08T03:01:37.984002Z","end":"2026-07-08T03:01:38.155589Z","steps":["trace[1970936801] 'read index received' (duration: 168.155622ms)","trace[1970936801] 'applied index is now lower than readState.Index' (duration: 3.429842ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:01:54.389645Z","caller":"traceutil/trace.go:171","msg":"trace[101942336] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"148.18105ms","start":"2026-07-08T03:01:54.241432Z","end":"2026-07-08T03:01:54.389613Z","steps":["trace[101942336] 'process raft request' (duration: 148.024207ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:01:54.427853Z","caller":"traceutil/trace.go:171","msg":"trace[62097375] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"185.871773ms","start":"2026-07-08T03:01:54.241942Z","end":"2026-07-08T03:01:54.427813Z","steps":["trace[62097375] 'process raft request' (duration: 185.629817ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:02:00.791962Z","caller":"traceutil/trace.go:171","msg":"trace[556533027] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"145.043956ms","start":"2026-07-08T03:02:00.646892Z","end":"2026-07-08T03:02:00.791936Z","steps":["trace[556533027] 'process raft request' (duration: 144.80491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:02:01.118747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.242635ms","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-08T03:02:01.118816Z","caller":"traceutil/trace.go:171","msg":"trace[1450425443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2118; }","duration":"119.325797ms","start":"2026-07-08T03:02:00.999475Z","end":"2026-07-08T03:02:01.118801Z","steps":["trace[1450425443] 'range keys from in-memory index tree' (duration: 119.024041ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:02:01.282258Z","caller":"traceutil/trace.go:171","msg":"trace[542232607] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"158.84333ms","start":"2026-07-08T03:02:01.123161Z","end":"2026-07-08T03:02:01.282004Z","steps":["trace[542232607] 'process raft request' (duration: 158.642405ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:02:01.284586Z","caller":"traceutil/trace.go:171","msg":"trace[510895853] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2204; }","duration":"101.666901ms","start":"2026-07-08T03:02:01.182899Z","end":"2026-07-08T03:02:01.284566Z","steps":["trace[510895853] 'read index received' (duration: 98.943196ms)","trace[510895853] 'applied index is now lower than readState.Index' (duration: 2.722925ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:02:01.284643Z","caller":"traceutil/trace.go:171","msg":"trace[220283671] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"113.844617ms","start":"2026-07-08T03:02:01.170781Z","end":"2026-07-08T03:02:01.284626Z","steps":["trace[220283671] 'process raft request' (duration: 113.738314ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:02:01.28464Z","caller":"traceutil/trace.go:171","msg":"trace[767685694] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"159.803302ms","start":"2026-07-08T03:02:01.124801Z","end":"2026-07-08T03:02:01.284604Z","steps":["trace[767685694] 'process raft request' (duration: 159.638899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:02:01.284717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:02:01.284764Z","caller":"traceutil/trace.go:171","msg":"trace[612979589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"101.875165ms","start":"2026-07-08T03:02:01.182875Z","end":"2026-07-08T03:02:01.28475Z","steps":["trace[612979589] 'agreement among raft nodes before linearized reading' (duration: 101.793023ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:02:01.541261Z","caller":"traceutil/trace.go:171","msg":"trace[126869736] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"115.699133ms","start":"2026-07-08T03:02:01.42554Z","end":"2026-07-08T03:02:01.541239Z","steps":["trace[126869736] 'process raft request' (duration: 115.557029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:03:22.079325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.240712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:03:22.079428Z","caller":"traceutil/trace.go:171","msg":"trace[103671443] transaction","detail":"{read_only:false; response_revision:2663; number_of_response:1; }","duration":"123.473922ms","start":"2026-07-08T03:03:21.955936Z","end":"2026-07-08T03:03:22.07941Z","steps":["trace[103671443] 'process raft request' (duration: 11.073936ms)","trace[103671443] 'compare' (duration: 112.081219ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:29.2298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.758967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:07:29.230093Z","caller":"traceutil/trace.go:171","msg":"trace[291108775] linearizableReadLoop","detail":"{readStateIndex:4907; appliedIndex:4906; }","duration":"173.703494ms","start":"2026-07-08T03:07:29.056366Z","end":"2026-07-08T03:07:29.230069Z","steps":["trace[291108775] 'read index received' (duration: 3.436054ms)","trace[291108775] 'applied index is now lower than readState.Index' (duration: 170.26593ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:29.230183Z","caller":"traceutil/trace.go:171","msg":"trace[528386099] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"324.690671ms","start":"2026-07-08T03:07:28.905447Z","end":"2026-07-08T03:07:29.230138Z","steps":["trace[528386099] 'process raft request' (duration: 154.435482ms)","trace[528386099] 'compare' (duration: 169.586913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:29.230361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.00014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9729f6ca-40e3-4fe5-822f-beef5eb1f977\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-08T03:07:29.230408Z","caller":"traceutil/trace.go:171","msg":"trace[1354813715] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9729f6ca-40e3-4fe5-822f-beef5eb1f977; range_end:; response_count:1; response_revision:4724; }","duration":"174.050081ms","start":"2026-07-08T03:07:29.056346Z","end":"2026-07-08T03:07:29.230396Z","steps":["trace[1354813715] 'agreement among raft nodes before linearized reading' (duration: 173.851247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:29.230409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.905431Z","time spent":"324.847445ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:29.240883Z","caller":"traceutil/trace.go:171","msg":"trace[1066299743] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"111.183525ms","start":"2026-07-08T03:07:29.129686Z","end":"2026-07-08T03:07:29.24087Z","steps":["trace[1066299743] 'process raft request' (duration: 111.049502ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:41.430501Z","caller":"traceutil/trace.go:171","msg":"trace[1264792654] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5074; }","duration":"138.8553ms","start":"2026-07-08T03:07:41.291624Z","end":"2026-07-08T03:07:41.430479Z","steps":["trace[1264792654] 'read index received' (duration: 138.84619ms)","trace[1264792654] 'applied index is now lower than readState.Index' (duration: 7.58µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:41.430719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068255ms","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-08T03:07:41.430769Z","caller":"traceutil/trace.go:171","msg":"trace[1211330927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4884; }","duration":"139.210838ms","start":"2026-07-08T03:07:41.291545Z","end":"2026-07-08T03:07:41.430756Z","steps":["trace[1211330927] 'agreement among raft nodes before linearized reading' (duration: 139.030394ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:41.430526Z","caller":"traceutil/trace.go:171","msg":"trace[1443433145] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"147.127011ms","start":"2026-07-08T03:07:41.283346Z","end":"2026-07-08T03:07:41.430473Z","steps":["trace[1443433145] 'process raft request' (duration: 146.969617ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:41.751931Z","caller":"traceutil/trace.go:171","msg":"trace[159740613] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5074; }","duration":"321.32844ms","start":"2026-07-08T03:07:41.430578Z","end":"2026-07-08T03:07:41.751907Z","steps":["trace[159740613] 'read index received' (duration: 321.241608ms)","trace[159740613] 'applied index is now lower than readState.Index' (duration: 85.562µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:41.75212Z","caller":"traceutil/trace.go:171","msg":"trace[1398217007] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"449.9188ms","start":"2026-07-08T03:07:41.302114Z","end":"2026-07-08T03:07:41.752033Z","steps":["trace[1398217007] 'process raft request' (duration: 449.589312ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:41.752176Z","caller":"traceutil/trace.go:171","msg":"trace[1165967712] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"444.564759ms","start":"2026-07-08T03:07:41.307579Z","end":"2026-07-08T03:07:41.752144Z","steps":["trace[1165967712] 'process raft request' (duration: 444.283573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:41.752334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:41.302092Z","time spent":"450.103855ms","remote":"127.0.0.1:48966","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-08T03:07:41.752372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:41.307562Z","time spent":"444.697463ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:41.75259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.941262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T03:07:41.752622Z","caller":"traceutil/trace.go:171","msg":"trace[2053192968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4886; }","duration":"342.022113ms","start":"2026-07-08T03:07:41.410592Z","end":"2026-07-08T03:07:41.752614Z","steps":["trace[2053192968] 'agreement among raft nodes before linearized reading' (duration: 341.909301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:41.752869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:41.410568Z","time spent":"342.067225ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T03:07:41.76174Z","caller":"traceutil/trace.go:171","msg":"trace[1809013228] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"326.516786ms","start":"2026-07-08T03:07:41.435207Z","end":"2026-07-08T03:07:41.761723Z","steps":["trace[1809013228] 'process raft request' (duration: 326.332752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:41.761981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:41.435191Z","time spent":"326.587779ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:09:01.14819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-07-08T03:09:01.393724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"243.498473ms","hash":3134057087,"current-db-size-bytes":55050240,"current-db-size":"55 MB","current-db-size-in-use-bytes":29466624,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T03:09:01.39378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3134057087,"revision":2868,"compact-revision":-1} {"level":"warn","ts":"2026-07-08T03:11:45.077185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.105039ms","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-08T03:11:45.077258Z","caller":"traceutil/trace.go:171","msg":"trace[2037482334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6689; }","duration":"232.222812ms","start":"2026-07-08T03:11:44.845019Z","end":"2026-07-08T03:11:45.077242Z","steps":["trace[2037482334] 'range keys from in-memory index tree' (duration: 231.961646ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:11:45.078291Z","caller":"traceutil/trace.go:171","msg":"trace[477750942] transaction","detail":"{read_only:false; response_revision:6690; number_of_response:1; }","duration":"114.228042ms","start":"2026-07-08T03:11:44.96403Z","end":"2026-07-08T03:11:45.078258Z","steps":["trace[477750942] 'process raft request' (duration: 106.142331ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:11:45.201713Z","caller":"traceutil/trace.go:171","msg":"trace[745364094] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"117.935589ms","start":"2026-07-08T03:11:45.083749Z","end":"2026-07-08T03:11:45.201685Z","steps":["trace[745364094] 'process raft request' (duration: 109.599343ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:12:00.91546Z","caller":"traceutil/trace.go:171","msg":"trace[602874261] linearizableReadLoop","detail":"{readStateIndex:7022; appliedIndex:7021; }","duration":"125.950416ms","start":"2026-07-08T03:12:00.789492Z","end":"2026-07-08T03:12:00.915443Z","steps":["trace[602874261] 'read index received' (duration: 122.447173ms)","trace[602874261] 'applied index is now lower than readState.Index' (duration: 3.502163ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:12:00.915503Z","caller":"traceutil/trace.go:171","msg":"trace[1008443103] transaction","detail":"{read_only:false; response_revision:6769; number_of_response:1; }","duration":"154.951619ms","start":"2026-07-08T03:12:00.760541Z","end":"2026-07-08T03:12:00.915493Z","steps":["trace[1008443103] 'process raft request' (duration: 151.394915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:12:00.915937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.425027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:1 size:6805"} {"level":"info","ts":"2026-07-08T03:12:00.916003Z","caller":"traceutil/trace.go:171","msg":"trace[1610564014] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:1; response_revision:6769; }","duration":"126.54828ms","start":"2026-07-08T03:12:00.78944Z","end":"2026-07-08T03:12:00.915988Z","steps":["trace[1610564014] 'agreement among raft nodes before linearized reading' (duration: 126.15254ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:13:21.28425Z","caller":"traceutil/trace.go:171","msg":"trace[1450819480] linearizableReadLoop","detail":"{readStateIndex:7468; appliedIndex:7467; }","duration":"137.521671ms","start":"2026-07-08T03:13:21.146709Z","end":"2026-07-08T03:13:21.28423Z","steps":["trace[1450819480] 'read index received' (duration: 137.451189ms)","trace[1450819480] 'applied index is now lower than readState.Index' (duration: 69.752µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:13:21.284405Z","caller":"traceutil/trace.go:171","msg":"trace[807944655] transaction","detail":"{read_only:false; response_revision:7199; number_of_response:1; }","duration":"204.065315ms","start":"2026-07-08T03:13:21.080231Z","end":"2026-07-08T03:13:21.284297Z","steps":["trace[807944655] 'process raft request' (duration: 203.872181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:21.284568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.825068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:13:21.284676Z","caller":"traceutil/trace.go:171","msg":"trace[1848954868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:7199; }","duration":"137.995041ms","start":"2026-07-08T03:13:21.146656Z","end":"2026-07-08T03:13:21.284651Z","steps":["trace[1848954868] 'agreement among raft nodes before linearized reading' (duration: 137.658974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:21.285369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.786661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:13:21.285459Z","caller":"traceutil/trace.go:171","msg":"trace[357858274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7200; }","duration":"101.926064ms","start":"2026-07-08T03:13:21.183512Z","end":"2026-07-08T03:13:21.285438Z","steps":["trace[357858274] 'agreement among raft nodes before linearized reading' (duration: 101.785171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:22.644466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870599695526449,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T03:13:23.055679Z","caller":"traceutil/trace.go:171","msg":"trace[1047368154] linearizableReadLoop","detail":"{readStateIndex:7473; appliedIndex:7472; }","duration":"911.304946ms","start":"2026-07-08T03:13:22.144351Z","end":"2026-07-08T03:13:23.055656Z","steps":["trace[1047368154] 'read index received' (duration: 911.233545ms)","trace[1047368154] 'applied index is now lower than readState.Index' (duration: 70.041µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:13:23.055896Z","caller":"traceutil/trace.go:171","msg":"trace[1849766275] transaction","detail":"{read_only:false; response_revision:7204; number_of_response:1; }","duration":"949.739906ms","start":"2026-07-08T03:13:22.106091Z","end":"2026-07-08T03:13:23.055831Z","steps":["trace[1849766275] 'process raft request' (duration: 949.396419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.055962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.596154ms","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-08T03:13:23.055996Z","caller":"traceutil/trace.go:171","msg":"trace[1403785055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7204; }","duration":"911.669315ms","start":"2026-07-08T03:13:22.144317Z","end":"2026-07-08T03:13:23.055986Z","steps":["trace[1403785055] 'agreement among raft nodes before linearized reading' (duration: 911.531662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.056036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:13:22.144302Z","time spent":"911.725766ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T03:13:23.056281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:13:22.106066Z","time spent":"949.923451ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:13:23.119147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.22048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T03:13:23.119158Z","caller":"traceutil/trace.go:171","msg":"trace[340816461] transaction","detail":"{read_only:false; response_revision:7205; number_of_response:1; }","duration":"421.750111ms","start":"2026-07-08T03:13:22.697364Z","end":"2026-07-08T03:13:23.119114Z","steps":["trace[340816461] 'process raft request' (duration: 421.345312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.119136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.444843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T03:13:23.11922Z","caller":"traceutil/trace.go:171","msg":"trace[750485793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7205; }","duration":"222.345143ms","start":"2026-07-08T03:13:22.89686Z","end":"2026-07-08T03:13:23.119205Z","steps":["trace[750485793] 'agreement among raft nodes before linearized reading' (duration: 222.150099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.11917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.679183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T03:13:23.119257Z","caller":"traceutil/trace.go:171","msg":"trace[575972015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7205; }","duration":"135.647868ms","start":"2026-07-08T03:13:22.983581Z","end":"2026-07-08T03:13:23.119229Z","steps":["trace[575972015] 'agreement among raft nodes before linearized reading' (duration: 135.371541ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:13:23.119288Z","caller":"traceutil/trace.go:171","msg":"trace[171921375] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:7205; }","duration":"412.862838ms","start":"2026-07-08T03:13:22.7064Z","end":"2026-07-08T03:13:23.119263Z","steps":["trace[171921375] 'agreement among raft nodes before linearized reading' (duration: 412.656573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.119301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:13:22.697341Z","time spent":"421.875174ms","remote":"127.0.0.1:48966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:13:23.119347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:13:22.706378Z","time spent":"412.95676ms","remote":"127.0.0.1:60856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":21,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-07-08T03:13:23.119745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.549632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:13:23.119851Z","caller":"traceutil/trace.go:171","msg":"trace[2097002632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7205; }","duration":"935.724316ms","start":"2026-07-08T03:13:22.184098Z","end":"2026-07-08T03:13:23.119822Z","steps":["trace[2097002632] 'agreement among raft nodes before linearized reading' (duration: 934.817275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:13:23.119931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:13:22.184082Z","time spent":"935.831939ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T03:14:01.153508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5561} {"level":"info","ts":"2026-07-08T03:14:01.23495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5561,"took":"80.09653ms","hash":4142948327,"current-db-size-bytes":55050240,"current-db-size":"55 MB","current-db-size-in-use-bytes":18530304,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-08T03:14:01.235028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142948327,"revision":5561,"compact-revision":2868} {"level":"info","ts":"2026-07-08T03:15:39.707896Z","caller":"traceutil/trace.go:171","msg":"trace[452836207] linearizableReadLoop","detail":"{readStateIndex:8192; appliedIndex:8191; }","duration":"106.150402ms","start":"2026-07-08T03:15:39.601728Z","end":"2026-07-08T03:15:39.707878Z","steps":["trace[452836207] 'read index received' (duration: 106.05455ms)","trace[452836207] 'applied index is now lower than readState.Index' (duration: 95.082µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:15:39.707929Z","caller":"traceutil/trace.go:171","msg":"trace[854768095] transaction","detail":"{read_only:false; response_revision:7894; number_of_response:1; }","duration":"110.674497ms","start":"2026-07-08T03:15:39.597233Z","end":"2026-07-08T03:15:39.707908Z","steps":["trace[854768095] 'process raft request' (duration: 110.527493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:15:39.708759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.006851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:274 size:212406"} {"level":"info","ts":"2026-07-08T03:15:39.708789Z","caller":"traceutil/trace.go:171","msg":"trace[1236108377] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:274; response_revision:7894; }","duration":"107.073512ms","start":"2026-07-08T03:15:39.601708Z","end":"2026-07-08T03:15:39.708781Z","steps":["trace[1236108377] 'agreement among raft nodes before linearized reading' (duration: 106.264554ms)"],"step_count":1}