{"level":"warn","ts":"2026-07-07T20:06:25.574454Z","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-07T20:06:25.574571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-07T20:06:25.574657Z","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-07T20:06:25.574678Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-07T20:06:25.574713Z","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-07T20:06:25.575672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-07-07T20:06:25.575822Z","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":16,"max-cpu-available":16,"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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-07T20:06:25.582749Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.605823ms"} {"level":"info","ts":"2026-07-07T20:06:25.58915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-07-07T20:06:25.589276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T20:06:25.5893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-07-07T20:06:25.58932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T20:06:25.589337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-07-07T20:06:25.589396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-07-07T20:06:25.597782Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T20:06:25.598986Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T20:06:25.600156Z","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-07T20:06:25.603046Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T20:06:25.603246Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T20:06:25.603314Z","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-07T20:06:25.603522Z","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-07T20:06:25.603591Z","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-07T20:06:25.604286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-07-07T20:06:25.605291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-07T20:06:25.607526Z","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-07T20:06:25.607599Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-07T20:06:25.60764Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-07T20:06:25.607802Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T20:06:25.607846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T20:06:25.990264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T20:06:25.990339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T20:06:25.990382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-07-07T20:06:25.990399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-07-07T20:06:25.990408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-07T20:06:25.990423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-07-07T20:06:25.990435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-07T20:06:25.991329Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T20:06:25.991382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:06:25.991552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:06:25.991682Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:06:25.99347Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:06:25.993591Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:06:25.993631Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:06:25.994999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-07-07T20:06:25.995177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T20:06:25.995214Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T20:06:25.995433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T20:06:37.977025Z","caller":"traceutil/trace.go:171","msg":"trace[1405117304] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"101.448127ms","start":"2026-07-07T20:06:37.875539Z","end":"2026-07-07T20:06:37.976988Z","steps":["trace[1405117304] 'process raft request' (duration: 101.279162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:38.216828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.004531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:06:38.216965Z","caller":"traceutil/trace.go:171","msg":"trace[643736127] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"235.824475ms","start":"2026-07-07T20:06:37.981122Z","end":"2026-07-07T20:06:38.216947Z","steps":["trace[643736127] 'process raft request' (duration: 124.158416ms)","trace[643736127] 'compare' (duration: 110.891777ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:09.528811Z","caller":"traceutil/trace.go:171","msg":"trace[1337110274] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"109.184517ms","start":"2026-07-07T20:07:09.419596Z","end":"2026-07-07T20:07:09.52878Z","steps":["trace[1337110274] 'process raft request' (duration: 108.59105ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:09.783604Z","caller":"traceutil/trace.go:171","msg":"trace[898791734] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"151.500527ms","start":"2026-07-07T20:07:09.632074Z","end":"2026-07-07T20:07:09.783574Z","steps":["trace[898791734] 'read index received' (duration: 82.562612ms)","trace[898791734] 'applied index is now lower than readState.Index' (duration: 68.936785ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:09.783685Z","caller":"traceutil/trace.go:171","msg":"trace[1257712473] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"193.486447ms","start":"2026-07-07T20:07:09.590178Z","end":"2026-07-07T20:07:09.783665Z","steps":["trace[1257712473] 'process raft request' (duration: 124.414988ms)","trace[1257712473] 'compare' (duration: 68.783991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:07:09.783964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.857727ms","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-07T20:07:09.784059Z","caller":"traceutil/trace.go:171","msg":"trace[282402782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"152.018331ms","start":"2026-07-07T20:07:09.632019Z","end":"2026-07-07T20:07:09.784037Z","steps":["trace[282402782] 'agreement among raft nodes before linearized reading' (duration: 151.663501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:10.532353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.584312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:07:10.532462Z","caller":"traceutil/trace.go:171","msg":"trace[1050503390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"586.734546ms","start":"2026-07-07T20:07:09.945701Z","end":"2026-07-07T20:07:10.532436Z","steps":["trace[1050503390] 'range keys from in-memory index tree' (duration: 586.462438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:10.532519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:09.945662Z","time spent":"586.843579ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T20:07:10.533037Z","caller":"traceutil/trace.go:171","msg":"trace[321899080] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"167.562832ms","start":"2026-07-07T20:07:10.365452Z","end":"2026-07-07T20:07:10.533015Z","steps":["trace[321899080] 'read index received' (duration: 166.662757ms)","trace[321899080] 'applied index is now lower than readState.Index' (duration: 899.045µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:10.533062Z","caller":"traceutil/trace.go:171","msg":"trace[1794214282] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"344.937753ms","start":"2026-07-07T20:07:10.188093Z","end":"2026-07-07T20:07:10.533031Z","steps":["trace[1794214282] 'process raft request' (duration: 344.225163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:10.5332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:10.188064Z","time spent":"345.053457ms","remote":"127.0.0.1:40252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:07:10.533396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.908811ms","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-07T20:07:10.533512Z","caller":"traceutil/trace.go:171","msg":"trace[1744320631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"168.087596ms","start":"2026-07-07T20:07:10.3654Z","end":"2026-07-07T20:07:10.533488Z","steps":["trace[1744320631] 'agreement among raft nodes before linearized reading' (duration: 167.677074ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:11.851933Z","caller":"traceutil/trace.go:171","msg":"trace[183521357] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"108.240732ms","start":"2026-07-07T20:07:11.743663Z","end":"2026-07-07T20:07:11.851904Z","steps":["trace[183521357] 'process raft request' (duration: 108.121818ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:11.852086Z","caller":"traceutil/trace.go:171","msg":"trace[1608524238] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"108.465318ms","start":"2026-07-07T20:07:11.743594Z","end":"2026-07-07T20:07:11.85206Z","steps":["trace[1608524238] 'process raft request' (duration: 107.860191ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:11.852115Z","caller":"traceutil/trace.go:171","msg":"trace[529347312] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"107.900772ms","start":"2026-07-07T20:07:11.744195Z","end":"2026-07-07T20:07:11.852096Z","steps":["trace[529347312] 'process raft request' (duration: 107.626564ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:11.965827Z","caller":"traceutil/trace.go:171","msg":"trace[493205043] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"108.444208ms","start":"2026-07-07T20:07:11.857357Z","end":"2026-07-07T20:07:11.965802Z","steps":["trace[493205043] 'process raft request' (duration: 106.750821ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.750565Z","caller":"traceutil/trace.go:171","msg":"trace[1836672332] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"115.324827ms","start":"2026-07-07T20:08:23.635208Z","end":"2026-07-07T20:08:23.750533Z","steps":["trace[1836672332] 'process raft request' (duration: 115.080771ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.939131Z","caller":"traceutil/trace.go:171","msg":"trace[1587272177] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"122.208808ms","start":"2026-07-07T20:08:23.81688Z","end":"2026-07-07T20:08:23.939089Z","steps":["trace[1587272177] 'process raft request' (duration: 121.986992ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.939208Z","caller":"traceutil/trace.go:171","msg":"trace[1874726472] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"122.274351ms","start":"2026-07-07T20:08:23.816901Z","end":"2026-07-07T20:08:23.939175Z","steps":["trace[1874726472] 'process raft request' (duration: 122.111366ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.939243Z","caller":"traceutil/trace.go:171","msg":"trace[50968329] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"122.284471ms","start":"2026-07-07T20:08:23.816937Z","end":"2026-07-07T20:08:23.939221Z","steps":["trace[50968329] 'process raft request' (duration: 122.114446ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.939566Z","caller":"traceutil/trace.go:171","msg":"trace[547048740] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"122.444855ms","start":"2026-07-07T20:08:23.817054Z","end":"2026-07-07T20:08:23.939499Z","steps":["trace[547048740] 'process raft request' (duration: 122.060404ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.939678Z","caller":"traceutil/trace.go:171","msg":"trace[11208307] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"121.412426ms","start":"2026-07-07T20:08:23.818249Z","end":"2026-07-07T20:08:23.939662Z","steps":["trace[11208307] 'process raft request' (duration: 121.1855ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:23.93964Z","caller":"traceutil/trace.go:171","msg":"trace[1857280409] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"107.361448ms","start":"2026-07-07T20:08:23.832264Z","end":"2026-07-07T20:08:23.939625Z","steps":["trace[1857280409] 'process raft request' (duration: 107.217974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:37.940836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.772017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10666"} {"level":"info","ts":"2026-07-07T20:08:37.940894Z","caller":"traceutil/trace.go:171","msg":"trace[238037319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1858; }","duration":"176.87563ms","start":"2026-07-07T20:08:37.764006Z","end":"2026-07-07T20:08:37.940882Z","steps":["trace[238037319] 'range keys from in-memory index tree' (duration: 176.615612ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.006222Z","caller":"traceutil/trace.go:171","msg":"trace[1470600532] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"206.805377ms","start":"2026-07-07T20:08:37.79937Z","end":"2026-07-07T20:08:38.006175Z","steps":["trace[1470600532] 'read index received' (duration: 206.634383ms)","trace[1470600532] 'applied index is now lower than readState.Index' (duration: 169.684µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:38.006274Z","caller":"traceutil/trace.go:171","msg":"trace[33988320] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"212.32425ms","start":"2026-07-07T20:08:37.793906Z","end":"2026-07-07T20:08:38.00623Z","steps":["trace[33988320] 'process raft request' (duration: 212.088693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:38.006436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.042164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-025d42a8-5463-4c24-ba30-616c645b6396\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-07-07T20:08:38.006498Z","caller":"traceutil/trace.go:171","msg":"trace[2042305534] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-025d42a8-5463-4c24-ba30-616c645b6396; range_end:; response_count:1; response_revision:1859; }","duration":"207.137216ms","start":"2026-07-07T20:08:37.799343Z","end":"2026-07-07T20:08:38.00648Z","steps":["trace[2042305534] 'agreement among raft nodes before linearized reading' (duration: 206.969051ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.105075Z","caller":"traceutil/trace.go:171","msg":"trace[1425865695] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"151.365054ms","start":"2026-07-07T20:08:37.953677Z","end":"2026-07-07T20:08:38.105042Z","steps":["trace[1425865695] 'process raft request' (duration: 115.824682ms)","trace[1425865695] 'compare' (duration: 35.276845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:08:38.105169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.862584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-07T20:08:38.105177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.475736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:08:38.105296Z","caller":"traceutil/trace.go:171","msg":"trace[750047672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"160.627621ms","start":"2026-07-07T20:08:37.94465Z","end":"2026-07-07T20:08:38.105278Z","steps":["trace[750047672] 'agreement among raft nodes before linearized reading' (duration: 160.446376ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.105246Z","caller":"traceutil/trace.go:171","msg":"trace[2115913729] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"255.974016ms","start":"2026-07-07T20:08:37.849246Z","end":"2026-07-07T20:08:38.10522Z","steps":["trace[2115913729] 'agreement among raft nodes before linearized reading' (duration: 255.75202ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.341428Z","caller":"traceutil/trace.go:171","msg":"trace[1912814645] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"228.58691ms","start":"2026-07-07T20:08:38.112819Z","end":"2026-07-07T20:08:38.341405Z","steps":["trace[1912814645] 'process raft request' (duration: 135.28875ms)","trace[1912814645] 'compare' (duration: 93.171776ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:38.408747Z","caller":"traceutil/trace.go:171","msg":"trace[2004305462] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"295.599132ms","start":"2026-07-07T20:08:38.113116Z","end":"2026-07-07T20:08:38.408715Z","steps":["trace[2004305462] 'process raft request' (duration: 295.362456ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.408789Z","caller":"traceutil/trace.go:171","msg":"trace[1211729746] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"295.358605ms","start":"2026-07-07T20:08:38.113366Z","end":"2026-07-07T20:08:38.408724Z","steps":["trace[1211729746] 'process raft request' (duration: 295.241742ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.409083Z","caller":"traceutil/trace.go:171","msg":"trace[781836542] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"295.496629ms","start":"2026-07-07T20:08:38.113568Z","end":"2026-07-07T20:08:38.409065Z","steps":["trace[781836542] 'process raft request' (duration: 295.082577ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:38.409149Z","caller":"traceutil/trace.go:171","msg":"trace[1725467840] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1933; }","duration":"189.068387ms","start":"2026-07-07T20:08:38.220057Z","end":"2026-07-07T20:08:38.409126Z","steps":["trace[1725467840] 'read index received' (duration: 27.948673ms)","trace[1725467840] 'applied index is now lower than readState.Index' (duration: 161.118734ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:38.40921Z","caller":"traceutil/trace.go:171","msg":"trace[765568942] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"293.515374ms","start":"2026-07-07T20:08:38.115676Z","end":"2026-07-07T20:08:38.409192Z","steps":["trace[765568942] 'process raft request' (duration: 293.337549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:38.409722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.643673ms","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-07T20:08:38.409795Z","caller":"traceutil/trace.go:171","msg":"trace[510787132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"189.760156ms","start":"2026-07-07T20:08:38.220018Z","end":"2026-07-07T20:08:38.409778Z","steps":["trace[510787132] 'agreement among raft nodes before linearized reading' (duration: 189.231431ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:09:16.731096Z","caller":"traceutil/trace.go:171","msg":"trace[823151118] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"131.151936ms","start":"2026-07-07T20:09:16.599905Z","end":"2026-07-07T20:09:16.731057Z","steps":["trace[823151118] 'process raft request' (duration: 91.536643ms)","trace[823151118] 'compare' (duration: 39.377686ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:09:22.24826Z","caller":"traceutil/trace.go:171","msg":"trace[67771073] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"237.007435ms","start":"2026-07-07T20:09:22.011229Z","end":"2026-07-07T20:09:22.248236Z","steps":["trace[67771073] 'process raft request' (duration: 236.86117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:09:28.941511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.996705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:09:28.941731Z","caller":"traceutil/trace.go:171","msg":"trace[1519683597] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"153.984476ms","start":"2026-07-07T20:09:28.787708Z","end":"2026-07-07T20:09:28.941693Z","steps":["trace[1519683597] 'process raft request' (duration: 44.61976ms)","trace[1519683597] 'compare' (duration: 108.769329ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:10:53.91962Z","caller":"traceutil/trace.go:171","msg":"trace[1741687065] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"136.766848ms","start":"2026-07-07T20:10:53.782815Z","end":"2026-07-07T20:10:53.919582Z","steps":["trace[1741687065] 'process raft request' (duration: 127.501262ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:10:54.352678Z","caller":"traceutil/trace.go:171","msg":"trace[1250916664] linearizableReadLoop","detail":"{readStateIndex:2954; appliedIndex:2953; }","duration":"116.216169ms","start":"2026-07-07T20:10:54.236429Z","end":"2026-07-07T20:10:54.352645Z","steps":["trace[1250916664] 'read index received' (duration: 115.990313ms)","trace[1250916664] 'applied index is now lower than readState.Index' (duration: 224.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:10:54.352742Z","caller":"traceutil/trace.go:171","msg":"trace[720979151] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"136.412949ms","start":"2026-07-07T20:10:54.216292Z","end":"2026-07-07T20:10:54.352705Z","steps":["trace[720979151] 'process raft request' (duration: 136.156572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:10:54.353098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.61429ms","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-07T20:10:54.353246Z","caller":"traceutil/trace.go:171","msg":"trace[1818526479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2845; }","duration":"116.858307ms","start":"2026-07-07T20:10:54.236357Z","end":"2026-07-07T20:10:54.353215Z","steps":["trace[1818526479] 'agreement among raft nodes before linearized reading' (duration: 116.410504ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:12:37.742459Z","caller":"traceutil/trace.go:171","msg":"trace[1468082807] transaction","detail":"{read_only:false; response_revision:3398; number_of_response:1; }","duration":"120.365661ms","start":"2026-07-07T20:12:37.622073Z","end":"2026-07-07T20:12:37.742439Z","steps":["trace[1468082807] 'process raft request' (duration: 119.9523ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:12:37.742646Z","caller":"traceutil/trace.go:171","msg":"trace[577618300] transaction","detail":"{read_only:false; response_revision:3401; number_of_response:1; }","duration":"114.801426ms","start":"2026-07-07T20:12:37.627818Z","end":"2026-07-07T20:12:37.742619Z","steps":["trace[577618300] 'process raft request' (duration: 114.690673ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:12:37.742793Z","caller":"traceutil/trace.go:171","msg":"trace[1048125089] transaction","detail":"{read_only:false; response_revision:3400; number_of_response:1; }","duration":"117.925233ms","start":"2026-07-07T20:12:37.624839Z","end":"2026-07-07T20:12:37.742765Z","steps":["trace[1048125089] 'process raft request' (duration: 117.588834ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:12:37.742918Z","caller":"traceutil/trace.go:171","msg":"trace[1627840451] transaction","detail":"{read_only:false; response_revision:3399; number_of_response:1; }","duration":"120.32015ms","start":"2026-07-07T20:12:37.622555Z","end":"2026-07-07T20:12:37.742875Z","steps":["trace[1627840451] 'process raft request' (duration: 119.677763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:12:37.743012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.243722ms","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-07T20:12:37.743078Z","caller":"traceutil/trace.go:171","msg":"trace[737708767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3401; }","duration":"100.367387ms","start":"2026-07-07T20:12:37.642695Z","end":"2026-07-07T20:12:37.743062Z","steps":["trace[737708767] 'agreement among raft nodes before linearized reading' (duration: 100.177771ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:12:37.743225Z","caller":"traceutil/trace.go:171","msg":"trace[1312864532] transaction","detail":"{read_only:false; response_revision:3396; number_of_response:1; }","duration":"120.629819ms","start":"2026-07-07T20:12:37.621494Z","end":"2026-07-07T20:12:37.742124Z","steps":["trace[1312864532] 'process raft request' (duration: 94.37328ms)","trace[1312864532] 'compare' (duration: 25.93671ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:12:37.743235Z","caller":"traceutil/trace.go:171","msg":"trace[1613297363] transaction","detail":"{read_only:false; response_revision:3397; number_of_response:1; }","duration":"120.124345ms","start":"2026-07-07T20:12:37.621939Z","end":"2026-07-07T20:12:37.742064Z","steps":["trace[1613297363] 'process raft request' (duration: 120.033062ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:40.904189Z","caller":"traceutil/trace.go:171","msg":"trace[669970591] linearizableReadLoop","detail":"{readStateIndex:5035; appliedIndex:5034; }","duration":"103.50509ms","start":"2026-07-07T20:14:40.800634Z","end":"2026-07-07T20:14:40.90414Z","steps":["trace[669970591] 'read index received' (duration: 103.14189ms)","trace[669970591] 'applied index is now lower than readState.Index' (duration: 362.13µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:14:40.904415Z","caller":"traceutil/trace.go:171","msg":"trace[1435770635] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"107.512272ms","start":"2026-07-07T20:14:40.796886Z","end":"2026-07-07T20:14:40.904399Z","steps":["trace[1435770635] 'process raft request' (duration: 107.015618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:14:40.904601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.944852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-07T20:14:40.90469Z","caller":"traceutil/trace.go:171","msg":"trace[1491563635] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4850; }","duration":"104.068205ms","start":"2026-07-07T20:14:40.800602Z","end":"2026-07-07T20:14:40.90467Z","steps":["trace[1491563635] 'agreement among raft nodes before linearized reading' (duration: 103.829839ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:40.910737Z","caller":"traceutil/trace.go:171","msg":"trace[877319857] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"109.705592ms","start":"2026-07-07T20:14:40.801006Z","end":"2026-07-07T20:14:40.910711Z","steps":["trace[877319857] 'process raft request' (duration: 109.547627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:14:40.910864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.946789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-07T20:14:40.910895Z","caller":"traceutil/trace.go:171","msg":"trace[1922275126] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4851; }","duration":"110.00166ms","start":"2026-07-07T20:14:40.800883Z","end":"2026-07-07T20:14:40.910885Z","steps":["trace[1922275126] 'agreement among raft nodes before linearized reading' (duration: 109.915538ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:46.354788Z","caller":"traceutil/trace.go:171","msg":"trace[1993118516] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"117.282842ms","start":"2026-07-07T20:14:46.237482Z","end":"2026-07-07T20:14:46.354765Z","steps":["trace[1993118516] 'process raft request' (duration: 117.142298ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:46.784972Z","caller":"traceutil/trace.go:171","msg":"trace[97992814] linearizableReadLoop","detail":"{readStateIndex:5208; appliedIndex:5207; }","duration":"100.95268ms","start":"2026-07-07T20:14:46.684003Z","end":"2026-07-07T20:14:46.784956Z","steps":["trace[97992814] 'read index received' (duration: 100.843477ms)","trace[97992814] 'applied index is now lower than readState.Index' (duration: 108.573µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:14:46.785048Z","caller":"traceutil/trace.go:171","msg":"trace[1152773965] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"174.982722ms","start":"2026-07-07T20:14:46.610014Z","end":"2026-07-07T20:14:46.784997Z","steps":["trace[1152773965] 'process raft request' (duration: 174.835458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:14:46.785161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.144495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-07T20:14:46.78519Z","caller":"traceutil/trace.go:171","msg":"trace[988140809] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5019; }","duration":"101.224747ms","start":"2026-07-07T20:14:46.683958Z","end":"2026-07-07T20:14:46.785182Z","steps":["trace[988140809] 'agreement among raft nodes before linearized reading' (duration: 101.106274ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:57.836003Z","caller":"traceutil/trace.go:171","msg":"trace[1834819487] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"101.829033ms","start":"2026-07-07T20:14:57.734155Z","end":"2026-07-07T20:14:57.835984Z","steps":["trace[1834819487] 'process raft request' (duration: 101.777622ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:14:57.836038Z","caller":"traceutil/trace.go:171","msg":"trace[764045012] linearizableReadLoop","detail":"{readStateIndex:5443; appliedIndex:5442; }","duration":"104.077496ms","start":"2026-07-07T20:14:57.731937Z","end":"2026-07-07T20:14:57.836014Z","steps":["trace[764045012] 'read index received' (duration: 28.382947ms)","trace[764045012] 'applied index is now lower than readState.Index' (duration: 75.689359ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:14:57.836044Z","caller":"traceutil/trace.go:171","msg":"trace[1336915836] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"116.147381ms","start":"2026-07-07T20:14:57.719858Z","end":"2026-07-07T20:14:57.836006Z","steps":["trace[1336915836] 'process raft request' (duration: 40.360069ms)","trace[1336915836] 'compare' (duration: 75.600777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:14:57.836209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.314576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vb62s\" ","response":"range_response_count:1 size:7816"} {"level":"info","ts":"2026-07-07T20:14:57.836235Z","caller":"traceutil/trace.go:171","msg":"trace[504846774] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-vb62s; range_end:; response_count:1; response_revision:5247; }","duration":"120.376548ms","start":"2026-07-07T20:14:57.715851Z","end":"2026-07-07T20:14:57.836228Z","steps":["trace[504846774] 'agreement among raft nodes before linearized reading' (duration: 120.241034ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:15:06.675686Z","caller":"traceutil/trace.go:171","msg":"trace[967166306] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"131.342062ms","start":"2026-07-07T20:15:06.544317Z","end":"2026-07-07T20:15:06.675659Z","steps":["trace[967166306] 'process raft request' (duration: 131.294561ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:15:06.675761Z","caller":"traceutil/trace.go:171","msg":"trace[1988030474] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"131.545818ms","start":"2026-07-07T20:15:06.544158Z","end":"2026-07-07T20:15:06.675704Z","steps":["trace[1988030474] 'process raft request' (duration: 121.617402ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:16:09.469558Z","caller":"traceutil/trace.go:171","msg":"trace[1528377363] transaction","detail":"{read_only:false; response_revision:6011; number_of_response:1; }","duration":"105.845023ms","start":"2026-07-07T20:16:09.363686Z","end":"2026-07-07T20:16:09.469531Z","steps":["trace[1528377363] 'process raft request' (duration: 105.656617ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:16:26.203165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3022} {"level":"info","ts":"2026-07-07T20:16:26.285008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3022,"took":"80.407658ms","hash":1645864076,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":32194560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-07T20:16:26.285081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1645864076,"revision":3022,"compact-revision":-1} {"level":"info","ts":"2026-07-07T20:21:15.949645Z","caller":"traceutil/trace.go:171","msg":"trace[88612273] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"176.90144ms","start":"2026-07-07T20:21:15.772728Z","end":"2026-07-07T20:21:15.949629Z","steps":["trace[88612273] 'process raft request' (duration: 176.791847ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:15.949694Z","caller":"traceutil/trace.go:171","msg":"trace[1589431082] linearizableReadLoop","detail":"{readStateIndex:8553; appliedIndex:8553; }","duration":"170.937885ms","start":"2026-07-07T20:21:15.778727Z","end":"2026-07-07T20:21:15.949665Z","steps":["trace[1589431082] 'read index received' (duration: 170.924644ms)","trace[1589431082] 'applied index is now lower than readState.Index' (duration: 10.601µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:21:15.949961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.211022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T20:21:15.95Z","caller":"traceutil/trace.go:171","msg":"trace[214771339] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8261; }","duration":"171.309705ms","start":"2026-07-07T20:21:15.778677Z","end":"2026-07-07T20:21:15.949987Z","steps":["trace[214771339] 'agreement among raft nodes before linearized reading' (duration: 171.116009ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:16.134215Z","caller":"traceutil/trace.go:171","msg":"trace[185482618] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"278.599167ms","start":"2026-07-07T20:21:15.855593Z","end":"2026-07-07T20:21:16.134192Z","steps":["trace[185482618] 'process raft request' (duration: 278.408322ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:16.134376Z","caller":"traceutil/trace.go:171","msg":"trace[1319844393] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"246.827717ms","start":"2026-07-07T20:21:15.887539Z","end":"2026-07-07T20:21:16.134367Z","steps":["trace[1319844393] 'process raft request' (duration: 246.58162ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:16.134406Z","caller":"traceutil/trace.go:171","msg":"trace[1520211912] linearizableReadLoop","detail":"{readStateIndex:8555; appliedIndex:8553; }","duration":"184.610983ms","start":"2026-07-07T20:21:15.949764Z","end":"2026-07-07T20:21:16.134374Z","steps":["trace[1520211912] 'read index received' (duration: 184.247523ms)","trace[1520211912] 'applied index is now lower than readState.Index' (duration: 358.92µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:21:16.134557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.125423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:21:16.134709Z","caller":"traceutil/trace.go:171","msg":"trace[1187099646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8263; }","duration":"187.320269ms","start":"2026-07-07T20:21:15.947367Z","end":"2026-07-07T20:21:16.134687Z","steps":["trace[1187099646] 'agreement among raft nodes before linearized reading' (duration: 187.087852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:21:16.134644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.253856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qr9zf\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-07-07T20:21:16.134785Z","caller":"traceutil/trace.go:171","msg":"trace[1694110971] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"142.079786ms","start":"2026-07-07T20:21:15.992678Z","end":"2026-07-07T20:21:16.134758Z","steps":["trace[1694110971] 'process raft request' (duration: 141.961592ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:16.134855Z","caller":"traceutil/trace.go:171","msg":"trace[479309633] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qr9zf; range_end:; response_count:1; response_revision:8263; }","duration":"309.530273ms","start":"2026-07-07T20:21:15.825283Z","end":"2026-07-07T20:21:16.134813Z","steps":["trace[479309633] 'agreement among raft nodes before linearized reading' (duration: 309.182994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:21:16.134986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:21:15.825266Z","time spent":"309.685148ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-qr9zf\" "} {"level":"info","ts":"2026-07-07T20:21:26.210097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6154} {"level":"info","ts":"2026-07-07T20:21:26.306151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6154,"took":"93.755117ms","hash":2088623030,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":23576576,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-07T20:21:26.306223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2088623030,"revision":6154,"compact-revision":3022} {"level":"info","ts":"2026-07-07T20:21:27.608253Z","caller":"traceutil/trace.go:171","msg":"trace[922215951] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"109.550824ms","start":"2026-07-07T20:21:27.498681Z","end":"2026-07-07T20:21:27.608232Z","steps":["trace[922215951] 'process raft request' (duration: 109.422001ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:28.490554Z","caller":"traceutil/trace.go:171","msg":"trace[394343494] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8621; }","duration":"279.971696ms","start":"2026-07-07T20:21:28.210559Z","end":"2026-07-07T20:21:28.490531Z","steps":["trace[394343494] 'read index received' (duration: 43.344201ms)","trace[394343494] 'applied index is now lower than readState.Index' (duration: 236.626875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:21:28.491001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.426248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qr9zf\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-07-07T20:21:28.491038Z","caller":"traceutil/trace.go:171","msg":"trace[1466206433] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qr9zf; range_end:; response_count:1; response_revision:8327; }","duration":"280.476739ms","start":"2026-07-07T20:21:28.210551Z","end":"2026-07-07T20:21:28.491028Z","steps":["trace[1466206433] 'agreement among raft nodes before linearized reading' (duration: 280.341006ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:21:28.491184Z","caller":"traceutil/trace.go:171","msg":"trace[1964877800] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"314.336337ms","start":"2026-07-07T20:21:28.176839Z","end":"2026-07-07T20:21:28.491175Z","steps":["trace[1964877800] 'process raft request' (duration: 313.35313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:21:28.491253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:21:28.176827Z","time spent":"314.373949ms","remote":"127.0.0.1:40428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:22:09.447764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.152096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T20:22:09.447843Z","caller":"traceutil/trace.go:171","msg":"trace[1223267180] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:8643; }","duration":"125.25858ms","start":"2026-07-07T20:22:09.32257Z","end":"2026-07-07T20:22:09.447829Z","steps":["trace[1223267180] 'range keys from in-memory index tree' (duration: 125.073325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:22:09.448161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.482265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:22:09.448398Z","caller":"traceutil/trace.go:171","msg":"trace[1369063682] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"125.783934ms","start":"2026-07-07T20:22:09.322589Z","end":"2026-07-07T20:22:09.448373Z","steps":["trace[1369063682] 'process raft request' (duration: 16.929699ms)","trace[1369063682] 'compare' (duration: 108.278579ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:22:10.297509Z","caller":"traceutil/trace.go:171","msg":"trace[843155679] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8956; }","duration":"128.660674ms","start":"2026-07-07T20:22:10.168821Z","end":"2026-07-07T20:22:10.297482Z","steps":["trace[843155679] 'read index received' (duration: 76.672154ms)","trace[843155679] 'applied index is now lower than readState.Index' (duration: 51.98343ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:22:10.297657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.808268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:22:10.297711Z","caller":"traceutil/trace.go:171","msg":"trace[1144708050] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8649; }","duration":"128.909751ms","start":"2026-07-07T20:22:10.168784Z","end":"2026-07-07T20:22:10.297694Z","steps":["trace[1144708050] 'agreement among raft nodes before linearized reading' (duration: 128.808178ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:22:10.297928Z","caller":"traceutil/trace.go:171","msg":"trace[172773194] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"143.698411ms","start":"2026-07-07T20:22:10.154201Z","end":"2026-07-07T20:22:10.2979Z","steps":["trace[172773194] 'process raft request' (duration: 142.633321ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:22:26.109615Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002395.wal"} {"level":"info","ts":"2026-07-07T20:24:01.705476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-07T20:24:01.71101Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-07T20:24:01.711134Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-07T20:26:26.21716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8318} {"level":"info","ts":"2026-07-07T20:26:26.292979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8318,"took":"73.402586ms","hash":938140529,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":24190976,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-07T20:26:26.293067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":938140529,"revision":8318,"compact-revision":6154} {"level":"info","ts":"2026-07-07T20:28:59.82356Z","caller":"traceutil/trace.go:171","msg":"trace[590674989] transaction","detail":"{read_only:false; response_revision:11801; number_of_response:1; }","duration":"147.973344ms","start":"2026-07-07T20:28:59.675541Z","end":"2026-07-07T20:28:59.823514Z","steps":["trace[590674989] 'process raft request' (duration: 72.411028ms)","trace[590674989] 'compare' (duration: 75.158205ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:29:00.859864Z","caller":"traceutil/trace.go:171","msg":"trace[653141748] transaction","detail":"{read_only:false; response_revision:11810; number_of_response:1; }","duration":"122.028765ms","start":"2026-07-07T20:29:00.737809Z","end":"2026-07-07T20:29:00.859838Z","steps":["trace[653141748] 'process raft request' (duration: 90.194761ms)","trace[653141748] 'compare' (duration: 31.561476ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:26.224276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10541} {"level":"info","ts":"2026-07-07T20:31:26.298532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10541,"took":"71.778879ms","hash":1471101260,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-07T20:31:26.298604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1471101260,"revision":10541,"compact-revision":8318} {"level":"warn","ts":"2026-07-07T20:32:24.957079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.959039ms","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-07T20:32:24.957161Z","caller":"traceutil/trace.go:171","msg":"trace[1034807990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13119; }","duration":"250.073982ms","start":"2026-07-07T20:32:24.707068Z","end":"2026-07-07T20:32:24.957142Z","steps":["trace[1034807990] 'range keys from in-memory index tree' (duration: 249.791574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:32:24.957415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.906147ms","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-07T20:32:24.957437Z","caller":"traceutil/trace.go:171","msg":"trace[1791068493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13119; }","duration":"105.963638ms","start":"2026-07-07T20:32:24.851468Z","end":"2026-07-07T20:32:24.957432Z","steps":["trace[1791068493] 'range keys from in-memory index tree' (duration: 105.831294ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:35:13.748034Z","caller":"traceutil/trace.go:171","msg":"trace[1423469158] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"110.678578ms","start":"2026-07-07T20:35:13.637327Z","end":"2026-07-07T20:35:13.748005Z","steps":["trace[1423469158] 'process raft request' (duration: 110.485652ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:35:16.808313Z","caller":"traceutil/trace.go:171","msg":"trace[2046848905] transaction","detail":"{read_only:false; response_revision:14336; number_of_response:1; }","duration":"146.48557ms","start":"2026-07-07T20:35:16.661802Z","end":"2026-07-07T20:35:16.808287Z","steps":["trace[2046848905] 'process raft request' (duration: 146.282034ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:35:52.558719Z","caller":"traceutil/trace.go:171","msg":"trace[113334254] linearizableReadLoop","detail":"{readStateIndex:15044; appliedIndex:15043; }","duration":"136.246416ms","start":"2026-07-07T20:35:52.422441Z","end":"2026-07-07T20:35:52.558688Z","steps":["trace[113334254] 'read index received' (duration: 135.929657ms)","trace[113334254] 'applied index is now lower than readState.Index' (duration: 315.699µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:35:52.558855Z","caller":"traceutil/trace.go:171","msg":"trace[571825481] transaction","detail":"{read_only:false; response_revision:14538; number_of_response:1; }","duration":"221.196921ms","start":"2026-07-07T20:35:52.337631Z","end":"2026-07-07T20:35:52.558827Z","steps":["trace[571825481] 'process raft request' (duration: 220.832551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:35:52.559159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.687689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T20:35:52.559221Z","caller":"traceutil/trace.go:171","msg":"trace[507522071] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:14538; }","duration":"136.815982ms","start":"2026-07-07T20:35:52.422387Z","end":"2026-07-07T20:35:52.559203Z","steps":["trace[507522071] 'agreement among raft nodes before linearized reading' (duration: 136.420241ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:36:17.076466Z","caller":"traceutil/trace.go:171","msg":"trace[392448995] linearizableReadLoop","detail":"{readStateIndex:15374; appliedIndex:15373; }","duration":"360.097321ms","start":"2026-07-07T20:36:16.716323Z","end":"2026-07-07T20:36:17.076421Z","steps":["trace[392448995] 'read index received' (duration: 343.670405ms)","trace[392448995] 'applied index is now lower than readState.Index' (duration: 16.425076ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:36:17.076603Z","caller":"traceutil/trace.go:171","msg":"trace[209656894] transaction","detail":"{read_only:false; response_revision:14855; number_of_response:1; }","duration":"426.897263ms","start":"2026-07-07T20:36:16.649669Z","end":"2026-07-07T20:36:17.076567Z","steps":["trace[209656894] 'process raft request' (duration: 410.20839ms)","trace[209656894] 'compare' (duration: 16.298172ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:36:17.076667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.325847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T20:36:17.07675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:36:16.649645Z","time spent":"427.025916ms","remote":"127.0.0.1:40428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:36:17.076781Z","caller":"traceutil/trace.go:171","msg":"trace[475775645] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:14855; }","duration":"360.44456ms","start":"2026-07-07T20:36:16.716307Z","end":"2026-07-07T20:36:17.076751Z","steps":["trace[475775645] 'agreement among raft nodes before linearized reading' (duration: 360.285995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:36:17.076854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:36:16.716251Z","time spent":"360.584374ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-07-07T20:36:17.111959Z","caller":"traceutil/trace.go:171","msg":"trace[871348470] transaction","detail":"{read_only:false; response_revision:14856; number_of_response:1; }","duration":"276.990197ms","start":"2026-07-07T20:36:16.834933Z","end":"2026-07-07T20:36:17.111923Z","steps":["trace[871348470] 'process raft request' (duration: 276.821713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:36:17.112007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.059254ms","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-07T20:36:17.112039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.227929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:36:17.112095Z","caller":"traceutil/trace.go:171","msg":"trace[1351326607] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:14856; }","duration":"380.188868ms","start":"2026-07-07T20:36:16.731883Z","end":"2026-07-07T20:36:17.112071Z","steps":["trace[1351326607] 'agreement among raft nodes before linearized reading' (duration: 380.021293ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:36:17.112124Z","caller":"traceutil/trace.go:171","msg":"trace[1943942696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14856; }","duration":"165.362783ms","start":"2026-07-07T20:36:16.946741Z","end":"2026-07-07T20:36:17.112103Z","steps":["trace[1943942696] 'agreement among raft nodes before linearized reading' (duration: 165.22977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:36:17.112148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:36:16.731871Z","time spent":"380.266219ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-07-07T20:36:26.230662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12793} {"level":"info","ts":"2026-07-07T20:36:26.310892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12793,"took":"77.548959ms","hash":3143375702,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":28864512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-07T20:36:26.310969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3143375702,"revision":12793,"compact-revision":10541} {"level":"warn","ts":"2026-07-07T20:37:08.402807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.545039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737167"} {"level":"warn","ts":"2026-07-07T20:37:08.402823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.103179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737167"} {"level":"info","ts":"2026-07-07T20:37:08.402882Z","caller":"traceutil/trace.go:171","msg":"trace[709009631] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15484; }","duration":"134.669142ms","start":"2026-07-07T20:37:08.268201Z","end":"2026-07-07T20:37:08.40287Z","steps":["trace[709009631] 'range keys from in-memory index tree' (duration: 133.745287ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:37:08.402903Z","caller":"traceutil/trace.go:171","msg":"trace[520563767] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15484; }","duration":"133.199702ms","start":"2026-07-07T20:37:08.269686Z","end":"2026-07-07T20:37:08.402886Z","steps":["trace[520563767] 'range keys from in-memory index tree' (duration: 132.385449ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:37:27.155844Z","caller":"traceutil/trace.go:171","msg":"trace[1204252313] transaction","detail":"{read_only:false; response_revision:15582; number_of_response:1; }","duration":"116.950361ms","start":"2026-07-07T20:37:27.038867Z","end":"2026-07-07T20:37:27.155818Z","steps":["trace[1204252313] 'process raft request' (duration: 116.432667ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:37:28.223863Z","caller":"traceutil/trace.go:171","msg":"trace[410005019] transaction","detail":"{read_only:false; response_revision:15589; number_of_response:1; }","duration":"133.721626ms","start":"2026-07-07T20:37:28.090109Z","end":"2026-07-07T20:37:28.22383Z","steps":["trace[410005019] 'process raft request' (duration: 133.434338ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:37:28.405815Z","caller":"traceutil/trace.go:171","msg":"trace[2136157061] transaction","detail":"{read_only:false; response_revision:15590; number_of_response:1; }","duration":"123.561265ms","start":"2026-07-07T20:37:28.282231Z","end":"2026-07-07T20:37:28.405793Z","steps":["trace[2136157061] 'process raft request' (duration: 88.208215ms)","trace[2136157061] 'compare' (duration: 35.233116ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:41:26.23658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14934} {"level":"info","ts":"2026-07-07T20:41:26.331884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14934,"took":"92.183133ms","hash":3870778080,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":30109696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-07T20:41:26.331961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3870778080,"revision":14934,"compact-revision":12793} {"level":"info","ts":"2026-07-07T20:46:26.243318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17375} {"level":"info","ts":"2026-07-07T20:46:26.345678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17375,"took":"98.709155ms","hash":3960721214,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":29622272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-07T20:46:26.345765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960721214,"revision":17375,"compact-revision":14934} {"level":"info","ts":"2026-07-07T20:47:00.445547Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-07T20:47:00.451194Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-07T20:47:00.451313Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-07T20:51:26.250094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19147} {"level":"info","ts":"2026-07-07T20:51:26.338521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19147,"took":"85.722705ms","hash":785594476,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":24309760,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-07T20:51:26.338581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":785594476,"revision":19147,"compact-revision":17375}