{"level":"warn","ts":"2026-07-21T20:51:42.385985Z","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-21T20:51:42.386328Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-21T20:51:42.386407Z","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-21T20:51:42.386419Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-21T20:51:42.386437Z","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-21T20:51:42.387123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-21T20:51:42.387203Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-21T20:51:42.394088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.672032ms"} {"level":"info","ts":"2026-07-21T20:51:42.40116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-21T20:51:42.401238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:51:42.401293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-21T20:51:42.401314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:51:42.401323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-21T20:51:42.401412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-21T20:51:42.407813Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:51:42.408989Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:51:42.409834Z","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-21T20:51:42.411733Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:51:42.411971Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:51:42.412794Z","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-21T20:51:42.413097Z","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-21T20:51:42.413119Z","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-21T20:51:42.413885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-21T20:51:42.414177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-21T20:51:42.416133Z","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-21T20:51:42.416251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-21T20:51:42.416282Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-21T20:51:42.416365Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:51:42.416401Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:51:42.702356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:51:42.702432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:51:42.70247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-21T20:51:42.702492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:51:42.702504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-21T20:51:42.702519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-21T20:51:42.702534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-21T20:51:42.703327Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:42.704064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:51:42.70424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:42.704352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:42.704554Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:42.704643Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:42.704672Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:42.704885Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:51:42.704935Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:51:42.70784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:51:42.72478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-21T20:51:45.592644Z","caller":"traceutil/trace.go:171","msg":"trace[627200073] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"131.215846ms","start":"2026-07-21T20:51:45.461381Z","end":"2026-07-21T20:51:45.592597Z","steps":["trace[627200073] 'read index received' (duration: 131.068921ms)","trace[627200073] 'applied index is now lower than readState.Index' (duration: 146.375µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:45.592729Z","caller":"traceutil/trace.go:171","msg":"trace[958363972] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"132.343781ms","start":"2026-07-21T20:51:45.460356Z","end":"2026-07-21T20:51:45.592699Z","steps":["trace[958363972] 'process raft request' (duration: 132.133375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:45.59292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.477684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:51:45.592968Z","caller":"traceutil/trace.go:171","msg":"trace[221173504] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"131.598207ms","start":"2026-07-21T20:51:45.46136Z","end":"2026-07-21T20:51:45.592958Z","steps":["trace[221173504] 'agreement among raft nodes before linearized reading' (duration: 131.381951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:45.901404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.025344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-21T20:51:45.901567Z","caller":"traceutil/trace.go:171","msg":"trace[2015155687] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"301.216094ms","start":"2026-07-21T20:51:45.600334Z","end":"2026-07-21T20:51:45.90155Z","steps":["trace[2015155687] 'read index received' (duration: 196.011142ms)","trace[2015155687] 'applied index is now lower than readState.Index' (duration: 105.203392ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:45.901651Z","caller":"traceutil/trace.go:171","msg":"trace[1793742295] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"303.118335ms","start":"2026-07-21T20:51:45.598484Z","end":"2026-07-21T20:51:45.901602Z","steps":["trace[1793742295] 'process raft request' (duration: 197.923163ms)","trace[1793742295] 'compare' (duration: 103.852528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:45.90184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.472162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:51:45.901898Z","caller":"traceutil/trace.go:171","msg":"trace[1285069642] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"301.600056ms","start":"2026-07-21T20:51:45.600285Z","end":"2026-07-21T20:51:45.901885Z","steps":["trace[1285069642] 'agreement among raft nodes before linearized reading' (duration: 301.445581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:45.901971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:45.600274Z","time spent":"301.688269ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-07-21T20:51:45.902565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:45.598424Z","time spent":"303.337252ms","remote":"127.0.0.1:53048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:51:52.910821Z","caller":"traceutil/trace.go:171","msg":"trace[1106872242] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"122.78292ms","start":"2026-07-21T20:51:52.787992Z","end":"2026-07-21T20:51:52.910774Z","steps":["trace[1106872242] 'process raft request' (duration: 122.466711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:53.141231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.618094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:51:53.141463Z","caller":"traceutil/trace.go:171","msg":"trace[888069418] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"222.626586ms","start":"2026-07-21T20:51:52.918816Z","end":"2026-07-21T20:51:53.141442Z","steps":["trace[888069418] 'process raft request' (duration: 222.563514ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:53.141443Z","caller":"traceutil/trace.go:171","msg":"trace[1246359746] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"275.541616ms","start":"2026-07-21T20:51:52.865851Z","end":"2026-07-21T20:51:53.141393Z","steps":["trace[1246359746] 'process raft request' (duration: 67.663705ms)","trace[1246359746] 'compare' (duration: 207.406368ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:53.141551Z","caller":"traceutil/trace.go:171","msg":"trace[702439757] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"230.56097ms","start":"2026-07-21T20:51:52.910952Z","end":"2026-07-21T20:51:53.141513Z","steps":["trace[702439757] 'read index received' (duration: 22.649977ms)","trace[702439757] 'applied index is now lower than readState.Index' (duration: 207.907003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:53.141858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.704056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-21T20:51:53.141907Z","caller":"traceutil/trace.go:171","msg":"trace[1112315249] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:326; }","duration":"264.8246ms","start":"2026-07-21T20:51:52.87707Z","end":"2026-07-21T20:51:53.141894Z","steps":["trace[1112315249] 'agreement among raft nodes before linearized reading' (duration: 264.628264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:53.142041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.761662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-21T20:51:53.142093Z","caller":"traceutil/trace.go:171","msg":"trace[542994678] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:326; }","duration":"312.848845ms","start":"2026-07-21T20:51:52.82923Z","end":"2026-07-21T20:51:53.142079Z","steps":["trace[542994678] 'agreement among raft nodes before linearized reading' (duration: 312.747112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:53.142135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:51:52.829221Z","time spent":"312.905086ms","remote":"127.0.0.1:52874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-07-21T20:51:53.142261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.739947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:51:53.14241Z","caller":"traceutil/trace.go:171","msg":"trace[516942698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"169.012045ms","start":"2026-07-21T20:51:52.973361Z","end":"2026-07-21T20:51:53.142373Z","steps":["trace[516942698] 'agreement among raft nodes before linearized reading' (duration: 168.641725ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:22.883884Z","caller":"traceutil/trace.go:171","msg":"trace[1599951616] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"300.435902ms","start":"2026-07-21T20:52:22.58342Z","end":"2026-07-21T20:52:22.883856Z","steps":["trace[1599951616] 'process raft request' (duration: 221.599412ms)","trace[1599951616] 'compare' (duration: 78.617445ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:22.88437Z","caller":"traceutil/trace.go:171","msg":"trace[1977594525] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"299.137225ms","start":"2026-07-21T20:52:22.585193Z","end":"2026-07-21T20:52:22.88433Z","steps":["trace[1977594525] 'process raft request' (duration: 298.606394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:22.884428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:22.583399Z","time spent":"300.727358ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:52:22.886174Z","caller":"traceutil/trace.go:171","msg":"trace[2060847689] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"298.41258ms","start":"2026-07-21T20:52:22.587739Z","end":"2026-07-21T20:52:22.886151Z","steps":["trace[2060847689] 'process raft request' (duration: 296.489129ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:22.886358Z","caller":"traceutil/trace.go:171","msg":"trace[1514147231] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:537; }","duration":"252.497426ms","start":"2026-07-21T20:52:22.633794Z","end":"2026-07-21T20:52:22.886291Z","steps":["trace[1514147231] 'read index received' (duration: 171.279516ms)","trace[1514147231] 'applied index is now lower than readState.Index' (duration: 81.2157ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:22.886407Z","caller":"traceutil/trace.go:171","msg":"trace[523966128] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"208.437194ms","start":"2026-07-21T20:52:22.677952Z","end":"2026-07-21T20:52:22.886389Z","steps":["trace[523966128] 'process raft request' (duration: 207.413672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:22.886536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.696911ms","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-21T20:52:22.88802Z","caller":"traceutil/trace.go:171","msg":"trace[302473917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"254.261474ms","start":"2026-07-21T20:52:22.633744Z","end":"2026-07-21T20:52:22.888006Z","steps":["trace[302473917] 'agreement among raft nodes before linearized reading' (duration: 252.688ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:22.887564Z","caller":"traceutil/trace.go:171","msg":"trace[1475524645] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"194.288584ms","start":"2026-07-21T20:52:22.693256Z","end":"2026-07-21T20:52:22.887544Z","steps":["trace[1475524645] 'process raft request' (duration: 193.064089ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:33.063654Z","caller":"traceutil/trace.go:171","msg":"trace[1922462170] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"119.681764ms","start":"2026-07-21T20:52:32.943947Z","end":"2026-07-21T20:52:33.063628Z","steps":["trace[1922462170] 'read index received' (duration: 119.457909ms)","trace[1922462170] 'applied index is now lower than readState.Index' (duration: 222.895µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:33.063869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.898947ms","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-21T20:52:33.063925Z","caller":"traceutil/trace.go:171","msg":"trace[196106984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"120.00055ms","start":"2026-07-21T20:52:32.943908Z","end":"2026-07-21T20:52:33.063909Z","steps":["trace[196106984] 'agreement among raft nodes before linearized reading' (duration: 119.827547ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:33.063969Z","caller":"traceutil/trace.go:171","msg":"trace[1706043059] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"300.677633ms","start":"2026-07-21T20:52:32.763263Z","end":"2026-07-21T20:52:33.06394Z","steps":["trace[1706043059] 'process raft request' (duration: 300.213465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:33.064114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:32.763239Z","time spent":"300.795875ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:48.125055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.316725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:48.125143Z","caller":"traceutil/trace.go:171","msg":"trace[2144443616] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"251.898938ms","start":"2026-07-21T20:52:47.87323Z","end":"2026-07-21T20:52:48.125128Z","steps":["trace[2144443616] 'read index received' (duration: 99.296689ms)","trace[2144443616] 'applied index is now lower than readState.Index' (duration: 152.601389ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:48.125229Z","caller":"traceutil/trace.go:171","msg":"trace[276774078] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"267.210244ms","start":"2026-07-21T20:52:47.858005Z","end":"2026-07-21T20:52:48.125215Z","steps":["trace[276774078] 'process raft request' (duration: 114.646895ms)","trace[276774078] 'compare' (duration: 152.137042ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:48.125684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.456389ms","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-21T20:52:48.125715Z","caller":"traceutil/trace.go:171","msg":"trace[1298370691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"252.52122ms","start":"2026-07-21T20:52:47.873187Z","end":"2026-07-21T20:52:48.125708Z","steps":["trace[1298370691] 'agreement among raft nodes before linearized reading' (duration: 251.97693ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:21.817509Z","caller":"traceutil/trace.go:171","msg":"trace[777816398] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"213.810979ms","start":"2026-07-21T20:53:21.603652Z","end":"2026-07-21T20:53:21.817463Z","steps":["trace[777816398] 'read index received' (duration: 213.613117ms)","trace[777816398] 'applied index is now lower than readState.Index' (duration: 196.902µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:21.817803Z","caller":"traceutil/trace.go:171","msg":"trace[1948840958] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"217.8132ms","start":"2026-07-21T20:53:21.599951Z","end":"2026-07-21T20:53:21.817764Z","steps":["trace[1948840958] 'process raft request' (duration: 217.377455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:21.817841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.099243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:53:21.817894Z","caller":"traceutil/trace.go:171","msg":"trace[1412493293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"214.308286ms","start":"2026-07-21T20:53:21.603575Z","end":"2026-07-21T20:53:21.817883Z","steps":["trace[1412493293] 'agreement among raft nodes before linearized reading' (duration: 214.019762ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:09.380096Z","caller":"traceutil/trace.go:171","msg":"trace[280479033] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"133.741415ms","start":"2026-07-21T20:54:09.245862Z","end":"2026-07-21T20:54:09.379603Z","steps":["trace[280479033] 'process raft request' (duration: 133.525553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:24.232661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.478988ms","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-21T20:54:24.23275Z","caller":"traceutil/trace.go:171","msg":"trace[1460727034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1979; }","duration":"101.622619ms","start":"2026-07-21T20:54:24.131111Z","end":"2026-07-21T20:54:24.232733Z","steps":["trace[1460727034] 'range keys from in-memory index tree' (duration: 101.300656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.07431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.639832ms","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-21T20:54:58.074423Z","caller":"traceutil/trace.go:171","msg":"trace[1301889120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2171; }","duration":"133.803843ms","start":"2026-07-21T20:54:57.940597Z","end":"2026-07-21T20:54:58.074401Z","steps":["trace[1301889120] 'range keys from in-memory index tree' (duration: 133.195207ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.368738Z","caller":"traceutil/trace.go:171","msg":"trace[365978860] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2255; }","duration":"269.604961ms","start":"2026-07-21T20:54:58.099105Z","end":"2026-07-21T20:54:58.36871Z","steps":["trace[365978860] 'read index received' (duration: 269.4425ms)","trace[365978860] 'applied index is now lower than readState.Index' (duration: 161.181µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:58.369001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.876133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"info","ts":"2026-07-21T20:54:58.369067Z","caller":"traceutil/trace.go:171","msg":"trace[359168903] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2172; }","duration":"269.994565ms","start":"2026-07-21T20:54:58.09905Z","end":"2026-07-21T20:54:58.369045Z","steps":["trace[359168903] 'agreement among raft nodes before linearized reading' (duration: 269.729313ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.369388Z","caller":"traceutil/trace.go:171","msg":"trace[528071668] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"289.472841ms","start":"2026-07-21T20:54:58.079883Z","end":"2026-07-21T20:54:58.369356Z","steps":["trace[528071668] 'process raft request' (duration: 288.647305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.587835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.939285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:58.588094Z","caller":"traceutil/trace.go:171","msg":"trace[593706834] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"219.254366ms","start":"2026-07-21T20:54:58.368817Z","end":"2026-07-21T20:54:58.588072Z","steps":["trace[593706834] 'read index received' (duration: 100.967039ms)","trace[593706834] 'applied index is now lower than readState.Index' (duration: 118.285677ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:58.588305Z","caller":"traceutil/trace.go:171","msg":"trace[108995983] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"391.403439ms","start":"2026-07-21T20:54:58.196887Z","end":"2026-07-21T20:54:58.58829Z","steps":["trace[108995983] 'process raft request' (duration: 272.87719ms)","trace[108995983] 'compare' (duration: 117.801033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:58.588453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:58.196859Z","time spent":"391.51578ms","remote":"127.0.0.1:52936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:58.588826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.552283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-21T20:54:58.588871Z","caller":"traceutil/trace.go:171","msg":"trace[1357081479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"360.637143ms","start":"2026-07-21T20:54:58.228221Z","end":"2026-07-21T20:54:58.588858Z","steps":["trace[1357081479] 'agreement among raft nodes before linearized reading' (duration: 360.474982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.589169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:58.228207Z","time spent":"360.933415ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-21T20:54:58.588996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.133662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:58.589328Z","caller":"traceutil/trace.go:171","msg":"trace[743278844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"408.503705ms","start":"2026-07-21T20:54:58.180761Z","end":"2026-07-21T20:54:58.589265Z","steps":["trace[743278844] 'agreement among raft nodes before linearized reading' (duration: 408.087982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.58941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:58.18073Z","time spent":"408.663716ms","remote":"127.0.0.1:52704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:58.590345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.521209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:54:58.590385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.810187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:58.590439Z","caller":"traceutil/trace.go:171","msg":"trace[1148620078] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2174; }","duration":"115.927139ms","start":"2026-07-21T20:54:58.474494Z","end":"2026-07-21T20:54:58.590421Z","steps":["trace[1148620078] 'agreement among raft nodes before linearized reading' (duration: 115.828398ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.590437Z","caller":"traceutil/trace.go:171","msg":"trace[833312731] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2174; }","duration":"205.671141ms","start":"2026-07-21T20:54:58.384743Z","end":"2026-07-21T20:54:58.590415Z","steps":["trace[833312731] 'agreement among raft nodes before linearized reading' (duration: 205.507549ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.590587Z","caller":"traceutil/trace.go:171","msg":"trace[920728720] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"140.328328ms","start":"2026-07-21T20:54:58.450239Z","end":"2026-07-21T20:54:58.590568Z","steps":["trace[920728720] 'process raft request' (duration: 139.896294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.590746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.417094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T20:54:58.592328Z","caller":"traceutil/trace.go:171","msg":"trace[982554191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2174; }","duration":"187.990249ms","start":"2026-07-21T20:54:58.404277Z","end":"2026-07-21T20:54:58.592267Z","steps":["trace[982554191] 'agreement among raft nodes before linearized reading' (duration: 186.185943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:58.591083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.21249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:58.592524Z","caller":"traceutil/trace.go:171","msg":"trace[1865552767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2174; }","duration":"189.665793ms","start":"2026-07-21T20:54:58.402831Z","end":"2026-07-21T20:54:58.592497Z","steps":["trace[1865552767] 'agreement among raft nodes before linearized reading' (duration: 187.635475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:07.388442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.40219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-21T20:56:07.388548Z","caller":"traceutil/trace.go:171","msg":"trace[477022033] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2657; }","duration":"106.593417ms","start":"2026-07-21T20:56:07.281932Z","end":"2026-07-21T20:56:07.388526Z","steps":["trace[477022033] 'range keys from in-memory index tree' (duration: 106.046296ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:13.047914Z","caller":"traceutil/trace.go:171","msg":"trace[352455485] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"165.931285ms","start":"2026-07-21T20:56:12.881951Z","end":"2026-07-21T20:56:13.047883Z","steps":["trace[352455485] 'process raft request' (duration: 115.657905ms)","trace[352455485] 'compare' (duration: 50.093284ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:18.115543Z","caller":"traceutil/trace.go:171","msg":"trace[114554184] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"103.924389ms","start":"2026-07-21T20:56:18.011537Z","end":"2026-07-21T20:56:18.115462Z","steps":["trace[114554184] 'process raft request' (duration: 103.036297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:18.295669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.51621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:56:18.295759Z","caller":"traceutil/trace.go:171","msg":"trace[727129841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2713; }","duration":"115.664466ms","start":"2026-07-21T20:56:18.180075Z","end":"2026-07-21T20:56:18.29574Z","steps":["trace[727129841] 'range keys from in-memory index tree' (duration: 115.386176ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.425083Z","caller":"traceutil/trace.go:171","msg":"trace[1761414408] linearizableReadLoop","detail":"{readStateIndex:4779; appliedIndex:4778; }","duration":"116.78107ms","start":"2026-07-21T21:00:08.308267Z","end":"2026-07-21T21:00:08.425048Z","steps":["trace[1761414408] 'read index received' (duration: 54.786695ms)","trace[1761414408] 'applied index is now lower than readState.Index' (duration: 61.992535ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:08.425384Z","caller":"traceutil/trace.go:171","msg":"trace[847799213] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"150.149663ms","start":"2026-07-21T21:00:08.275182Z","end":"2026-07-21T21:00:08.425332Z","steps":["trace[847799213] 'process raft request' (duration: 87.941155ms)","trace[847799213] 'compare' (duration: 61.712251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:08.425899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.605232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60483"} {"level":"info","ts":"2026-07-21T21:00:08.425989Z","caller":"traceutil/trace.go:171","msg":"trace[144043268] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4602; }","duration":"117.707644ms","start":"2026-07-21T21:00:08.30826Z","end":"2026-07-21T21:00:08.425968Z","steps":["trace[144043268] 'agreement among raft nodes before linearized reading' (duration: 117.49123ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.559768Z","caller":"traceutil/trace.go:171","msg":"trace[1413213616] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"128.868285ms","start":"2026-07-21T21:00:08.430848Z","end":"2026-07-21T21:00:08.559716Z","steps":["trace[1413213616] 'process raft request' (duration: 123.297709ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.559821Z","caller":"traceutil/trace.go:171","msg":"trace[1670847811] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"127.130858ms","start":"2026-07-21T21:00:08.43267Z","end":"2026-07-21T21:00:08.559801Z","steps":["trace[1670847811] 'process raft request' (duration: 127.025967ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.55983Z","caller":"traceutil/trace.go:171","msg":"trace[52152446] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"127.576614ms","start":"2026-07-21T21:00:08.432229Z","end":"2026-07-21T21:00:08.559805Z","steps":["trace[52152446] 'process raft request' (duration: 127.428372ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.560013Z","caller":"traceutil/trace.go:171","msg":"trace[715381368] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"122.999635ms","start":"2026-07-21T21:00:08.437001Z","end":"2026-07-21T21:00:08.560001Z","steps":["trace[715381368] 'process raft request' (duration: 122.747201ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:08.560192Z","caller":"traceutil/trace.go:171","msg":"trace[268387570] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4779; }","duration":"111.958735ms","start":"2026-07-21T21:00:08.448205Z","end":"2026-07-21T21:00:08.560164Z","steps":["trace[268387570] 'read index received' (duration: 28.419818ms)","trace[268387570] 'applied index is now lower than readState.Index' (duration: 83.532967ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:08.560302Z","caller":"traceutil/trace.go:171","msg":"trace[864368125] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"118.294432ms","start":"2026-07-21T21:00:08.441994Z","end":"2026-07-21T21:00:08.560288Z","steps":["trace[864368125] 'process raft request' (duration: 117.848665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:08.561142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.9173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-07-21T21:00:08.561183Z","caller":"traceutil/trace.go:171","msg":"trace[757538403] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4609; }","duration":"112.996961ms","start":"2026-07-21T21:00:08.448173Z","end":"2026-07-21T21:00:08.56117Z","steps":["trace[757538403] 'agreement among raft nodes before linearized reading' (duration: 112.060576ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:13.311967Z","caller":"traceutil/trace.go:171","msg":"trace[1469198832] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"246.301464ms","start":"2026-07-21T21:00:13.065636Z","end":"2026-07-21T21:00:13.311938Z","steps":["trace[1469198832] 'process raft request' (duration: 246.114901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:13.312154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.587389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca2da308-b31b-4234-acd5-b8b2116585bd\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-21T21:00:13.312206Z","caller":"traceutil/trace.go:171","msg":"trace[1612129528] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca2da308-b31b-4234-acd5-b8b2116585bd; range_end:; response_count:1; response_revision:4702; }","duration":"202.723741ms","start":"2026-07-21T21:00:13.10947Z","end":"2026-07-21T21:00:13.312194Z","steps":["trace[1612129528] 'agreement among raft nodes before linearized reading' (duration: 202.546837ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:13.311906Z","caller":"traceutil/trace.go:171","msg":"trace[734682692] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"202.341884ms","start":"2026-07-21T21:00:13.109539Z","end":"2026-07-21T21:00:13.311881Z","steps":["trace[734682692] 'read index received' (duration: 202.163322ms)","trace[734682692] 'applied index is now lower than readState.Index' (duration: 177.642µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:13.312717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.244316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:13.312756Z","caller":"traceutil/trace.go:171","msg":"trace[430276816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4702; }","duration":"132.318698ms","start":"2026-07-21T21:00:13.180427Z","end":"2026-07-21T21:00:13.312745Z","steps":["trace[430276816] 'agreement among raft nodes before linearized reading' (duration: 132.261557ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:13.459975Z","caller":"traceutil/trace.go:171","msg":"trace[1770978511] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"138.722344ms","start":"2026-07-21T21:00:13.321232Z","end":"2026-07-21T21:00:13.459954Z","steps":["trace[1770978511] 'process raft request' (duration: 138.599522ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:17.176448Z","caller":"traceutil/trace.go:171","msg":"trace[885556189] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"109.13037ms","start":"2026-07-21T21:00:17.067293Z","end":"2026-07-21T21:00:17.176423Z","steps":["trace[885556189] 'process raft request' (duration: 108.920077ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:26.759579Z","caller":"traceutil/trace.go:171","msg":"trace[794378867] linearizableReadLoop","detail":"{readStateIndex:5065; appliedIndex:5064; }","duration":"105.753812ms","start":"2026-07-21T21:00:26.653805Z","end":"2026-07-21T21:00:26.759559Z","steps":["trace[794378867] 'read index received' (duration: 105.6293ms)","trace[794378867] 'applied index is now lower than readState.Index' (duration: 123.542µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:26.759631Z","caller":"traceutil/trace.go:171","msg":"trace[1083766979] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"198.690023ms","start":"2026-07-21T21:00:26.560909Z","end":"2026-07-21T21:00:26.759599Z","steps":["trace[1083766979] 'process raft request' (duration: 198.52691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:26.759796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.979775ms","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-21T21:00:26.759832Z","caller":"traceutil/trace.go:171","msg":"trace[2108301667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4880; }","duration":"106.069096ms","start":"2026-07-21T21:00:26.653753Z","end":"2026-07-21T21:00:26.759822Z","steps":["trace[2108301667] 'agreement among raft nodes before linearized reading' (duration: 105.928854ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:07.091303Z","caller":"traceutil/trace.go:171","msg":"trace[864722937] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"160.097573ms","start":"2026-07-21T21:01:06.931177Z","end":"2026-07-21T21:01:07.091275Z","steps":["trace[864722937] 'process raft request' (duration: 159.88536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:07.278074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.15033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:07.278348Z","caller":"traceutil/trace.go:171","msg":"trace[1915410845] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"181.144363ms","start":"2026-07-21T21:01:07.097172Z","end":"2026-07-21T21:01:07.278316Z","steps":["trace[1915410845] 'process raft request' (duration: 72.659938ms)","trace[1915410845] 'compare' (duration: 107.992538ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:07.278623Z","caller":"traceutil/trace.go:171","msg":"trace[747773774] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"181.177024ms","start":"2026-07-21T21:01:07.097414Z","end":"2026-07-21T21:01:07.278591Z","steps":["trace[747773774] 'process raft request' (duration: 180.864029ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:07.279695Z","caller":"traceutil/trace.go:171","msg":"trace[738860344] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"181.179374ms","start":"2026-07-21T21:01:07.097338Z","end":"2026-07-21T21:01:07.278517Z","steps":["trace[738860344] 'process raft request' (duration: 180.863109ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:07.28045Z","caller":"traceutil/trace.go:171","msg":"trace[361007145] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"182.805376ms","start":"2026-07-21T21:01:07.097628Z","end":"2026-07-21T21:01:07.280434Z","steps":["trace[361007145] 'process raft request' (duration: 180.717707ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:07.280599Z","caller":"traceutil/trace.go:171","msg":"trace[636913738] linearizableReadLoop","detail":"{readStateIndex:5444; appliedIndex:5440; }","duration":"124.276034ms","start":"2026-07-21T21:01:07.156312Z","end":"2026-07-21T21:01:07.280588Z","steps":["trace[636913738] 'read index received' (duration: 13.532238ms)","trace[636913738] 'applied index is now lower than readState.Index' (duration: 110.742886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:01:07.280938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.589529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:01:07.281032Z","caller":"traceutil/trace.go:171","msg":"trace[1809384125] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5250; }","duration":"124.732611ms","start":"2026-07-21T21:01:07.156274Z","end":"2026-07-21T21:01:07.281007Z","steps":["trace[1809384125] 'agreement among raft nodes before linearized reading' (duration: 124.540228ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:43.218472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2845} {"level":"info","ts":"2026-07-21T21:01:43.294893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2845,"took":"75.201545ms","hash":1658018030,"current-db-size-bytes":53764096,"current-db-size":"54 MB","current-db-size-in-use-bytes":29073408,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-21T21:01:43.294955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1658018030,"revision":2845,"compact-revision":-1} {"level":"info","ts":"2026-07-21T21:06:43.224722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5517} {"level":"info","ts":"2026-07-21T21:06:43.301618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5517,"took":"75.544255ms","hash":581379689,"current-db-size-bytes":53764096,"current-db-size":"54 MB","current-db-size-in-use-bytes":18178048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-21T21:06:43.301698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":581379689,"revision":5517,"compact-revision":2845}