{"level":"warn","ts":"2026-06-04T11:52:31.688459Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:52:31.688576Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-06-04T11:52:31.688946Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:52:31.688999Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-04T11:52:31.689027Z","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-06-04T11:52:31.691132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-06-04T11:52:31.691233Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-06-04T11:52:31.69695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.837914ms"} {"level":"info","ts":"2026-06-04T11:52:31.702914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-06-04T11:52:31.702994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T11:52:31.703018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-06-04T11:52:31.703031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T11:52:31.703046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-06-04T11:52:31.703085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-06-04T11:52:31.709141Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T11:52:31.71064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T11:52:31.715102Z","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-06-04T11:52:31.717101Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T11:52:31.717286Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T11:52:31.717361Z","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-06-04T11:52:31.717531Z","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-06-04T11:52:31.717568Z","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-06-04T11:52:31.722089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-06-04T11:52:31.723198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-04T11:52:31.724221Z","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-06-04T11:52:31.724936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T11:52:31.725026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T11:52:31.72433Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-04T11:52:31.72515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-04T11:52:31.803576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T11:52:31.803635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T11:52:31.80367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-06-04T11:52:31.80369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-06-04T11:52:31.803701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-04T11:52:31.803723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-06-04T11:52:31.803737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-04T11:52:31.804672Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:52:31.804931Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T11:52:31.804939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:52:31.805267Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T11:52:31.805305Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T11:52:31.804959Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:52:31.805391Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:52:31.805492Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:52:31.805526Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:52:31.808481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-06-04T11:52:31.81114Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-04T11:52:34.84148Z","caller":"traceutil/trace.go:171","msg":"trace[241744634] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"141.511162ms","start":"2026-06-04T11:52:34.699943Z","end":"2026-06-04T11:52:34.841454Z","steps":["trace[241744634] 'read index received' (duration: 141.2408ms)","trace[241744634] 'applied index is now lower than readState.Index' (duration: 269.522µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:52:34.841626Z","caller":"traceutil/trace.go:171","msg":"trace[1936659531] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"142.587782ms","start":"2026-06-04T11:52:34.698999Z","end":"2026-06-04T11:52:34.841586Z","steps":["trace[1936659531] 'process raft request' (duration: 142.29327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:52:34.841706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.745984ms","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-06-04T11:52:34.841769Z","caller":"traceutil/trace.go:171","msg":"trace[1262422176] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"141.823345ms","start":"2026-06-04T11:52:34.699934Z","end":"2026-06-04T11:52:34.841758Z","steps":["trace[1262422176] 'agreement among raft nodes before linearized reading' (duration: 141.661273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:52:35.103457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.028728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-04T11:52:35.103565Z","caller":"traceutil/trace.go:171","msg":"trace[352961406] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"255.355272ms","start":"2026-06-04T11:52:34.848194Z","end":"2026-06-04T11:52:35.103549Z","steps":["trace[352961406] 'read index received' (duration: 110.662127ms)","trace[352961406] 'applied index is now lower than readState.Index' (duration: 144.691995ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:52:35.103616Z","caller":"traceutil/trace.go:171","msg":"trace[2124489701] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"256.649433ms","start":"2026-06-04T11:52:34.846929Z","end":"2026-06-04T11:52:35.103578Z","steps":["trace[2124489701] 'process raft request' (duration: 111.969379ms)","trace[2124489701] 'compare' (duration: 143.885107ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:52:35.103663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.461513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-04T11:52:35.103696Z","caller":"traceutil/trace.go:171","msg":"trace[393526347] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"255.509803ms","start":"2026-06-04T11:52:34.848177Z","end":"2026-06-04T11:52:35.103687Z","steps":["trace[393526347] 'agreement among raft nodes before linearized reading' (duration: 255.414512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.43591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.808117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T11:53:11.43638Z","caller":"traceutil/trace.go:171","msg":"trace[115080397] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"207.352738ms","start":"2026-06-04T11:53:11.228991Z","end":"2026-06-04T11:53:11.436344Z","steps":["trace[115080397] 'process raft request' (duration: 65.761969ms)","trace[115080397] 'compare' (duration: 140.463413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:53:11.75187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.774844ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-04T11:53:11.753074Z","caller":"traceutil/trace.go:171","msg":"trace[1475919740] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"313.287884ms","start":"2026-06-04T11:53:11.439765Z","end":"2026-06-04T11:53:11.753053Z","steps":["trace[1475919740] 'read index received' (duration: 193.313826ms)","trace[1475919740] 'applied index is now lower than readState.Index' (duration: 119.973158ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:53:11.753151Z","caller":"traceutil/trace.go:171","msg":"trace[1180876877] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"390.174097ms","start":"2026-06-04T11:53:11.362969Z","end":"2026-06-04T11:53:11.753143Z","steps":["trace[1180876877] 'process raft request' (duration: 390.046832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.753232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:11.362942Z","time spent":"390.235099ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T11:53:11.753437Z","caller":"traceutil/trace.go:171","msg":"trace[1900621060] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"434.312735ms","start":"2026-06-04T11:53:11.319097Z","end":"2026-06-04T11:53:11.75341Z","steps":["trace[1900621060] 'process raft request' (duration: 433.408668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.75467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:11.319065Z","time spent":"435.550546ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-04T11:53:11.755819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.790966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mxj6q\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-06-04T11:53:11.755984Z","caller":"traceutil/trace.go:171","msg":"trace[813443913] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-mxj6q; range_end:; response_count:1; response_revision:564; }","duration":"316.13278ms","start":"2026-06-04T11:53:11.439728Z","end":"2026-06-04T11:53:11.75586Z","steps":["trace[813443913] 'agreement among raft nodes before linearized reading' (duration: 313.747712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.756125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:11.439712Z","time spent":"316.396281ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mxj6q\" "} {"level":"info","ts":"2026-06-04T11:53:11.780421Z","caller":"traceutil/trace.go:171","msg":"trace[491532969] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"221.983884ms","start":"2026-06-04T11:53:11.558411Z","end":"2026-06-04T11:53:11.780395Z","steps":["trace[491532969] 'process raft request' (duration: 221.952303ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:53:11.780802Z","caller":"traceutil/trace.go:171","msg":"trace[1040234670] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"332.934345ms","start":"2026-06-04T11:53:11.447796Z","end":"2026-06-04T11:53:11.78073Z","steps":["trace[1040234670] 'process raft request' (duration: 332.367922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.767011ms","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-06-04T11:53:11.781037Z","caller":"traceutil/trace.go:171","msg":"trace[1582362128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"174.866915ms","start":"2026-06-04T11:53:11.606159Z","end":"2026-06-04T11:53:11.781026Z","steps":["trace[1582362128] 'agreement among raft nodes before linearized reading' (duration: 174.73477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:11.781221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:11.44777Z","time spent":"333.134654ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T11:53:12.34747Z","caller":"traceutil/trace.go:171","msg":"trace[923597810] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"168.248018ms","start":"2026-06-04T11:53:12.179195Z","end":"2026-06-04T11:53:12.347443Z","steps":["trace[923597810] 'read index received' (duration: 68.218598ms)","trace[923597810] 'applied index is now lower than readState.Index' (duration: 100.02818ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:53:12.3477Z","caller":"traceutil/trace.go:171","msg":"trace[1384660159] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"168.619944ms","start":"2026-06-04T11:53:12.179067Z","end":"2026-06-04T11:53:12.347687Z","steps":["trace[1384660159] 'process raft request' (duration: 68.420367ms)","trace[1384660159] 'compare' (duration: 99.839582ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:53:12.348134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.919396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-06-04T11:53:12.348211Z","caller":"traceutil/trace.go:171","msg":"trace[383282506] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:587; }","duration":"169.03586ms","start":"2026-06-04T11:53:12.179158Z","end":"2026-06-04T11:53:12.348194Z","steps":["trace[383282506] 'agreement among raft nodes before linearized reading' (duration: 168.838052ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:53:19.019617Z","caller":"traceutil/trace.go:171","msg":"trace[1501575830] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"173.472831ms","start":"2026-06-04T11:53:18.846093Z","end":"2026-06-04T11:53:19.019566Z","steps":["trace[1501575830] 'process raft request' (duration: 172.649671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:53:19.389897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.261118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T11:53:19.390199Z","caller":"traceutil/trace.go:171","msg":"trace[534104257] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"311.729722ms","start":"2026-06-04T11:53:19.078438Z","end":"2026-06-04T11:53:19.390168Z","steps":["trace[534104257] 'process raft request' (duration: 311.627168ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:53:19.390275Z","caller":"traceutil/trace.go:171","msg":"trace[11667665] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"336.377171ms","start":"2026-06-04T11:53:19.053863Z","end":"2026-06-04T11:53:19.390241Z","steps":["trace[11667665] 'process raft request' (duration: 18.597563ms)","trace[11667665] 'compare' (duration: 317.03942ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:53:19.390518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:19.053834Z","time spent":"336.527437ms","remote":"127.0.0.1:49700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T11:53:19.390303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:53:19.078403Z","time spent":"311.854037ms","remote":"127.0.0.1:49784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-04T11:53:35.158467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.149541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T11:53:35.158636Z","caller":"traceutil/trace.go:171","msg":"trace[2072840060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"191.385238ms","start":"2026-06-04T11:53:34.967225Z","end":"2026-06-04T11:53:35.158611Z","steps":["trace[2072840060] 'range keys from in-memory index tree' (duration: 191.009056ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:54:02.375736Z","caller":"traceutil/trace.go:171","msg":"trace[596872044] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"259.105863ms","start":"2026-06-04T11:54:02.116606Z","end":"2026-06-04T11:54:02.375712Z","steps":["trace[596872044] 'process raft request' (duration: 258.921269ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:54:52.412798Z","caller":"traceutil/trace.go:171","msg":"trace[172261867] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"423.390603ms","start":"2026-06-04T11:54:51.989374Z","end":"2026-06-04T11:54:52.412764Z","steps":["trace[172261867] 'read index received' (duration: 423.117359ms)","trace[172261867] 'applied index is now lower than readState.Index' (duration: 272.024µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:54:52.412897Z","caller":"traceutil/trace.go:171","msg":"trace[2103800233] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"480.096978ms","start":"2026-06-04T11:54:51.932763Z","end":"2026-06-04T11:54:52.41286Z","steps":["trace[2103800233] 'process raft request' (duration: 479.822503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:54:52.413079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:54:51.932745Z","time spent":"480.234991ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T11:54:52.413019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.615757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a6b365d-34a8-4a6e-aa83-0cd7f5cb095b\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-06-04T11:54:52.413147Z","caller":"traceutil/trace.go:171","msg":"trace[1589393333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a6b365d-34a8-4a6e-aa83-0cd7f5cb095b; range_end:; response_count:1; response_revision:1844; }","duration":"423.805161ms","start":"2026-06-04T11:54:51.989324Z","end":"2026-06-04T11:54:52.413129Z","steps":["trace[1589393333] 'agreement among raft nodes before linearized reading' (duration: 423.617108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:54:52.41326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:54:51.9893Z","time spent":"423.924813ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a6b365d-34a8-4a6e-aa83-0cd7f5cb095b\" "} {"level":"info","ts":"2026-06-04T11:54:52.583076Z","caller":"traceutil/trace.go:171","msg":"trace[8180500] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"155.772339ms","start":"2026-06-04T11:54:52.427274Z","end":"2026-06-04T11:54:52.583046Z","steps":["trace[8180500] 'process raft request' (duration: 138.967836ms)","trace[8180500] 'compare' (duration: 16.663121ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:54:52.726988Z","caller":"traceutil/trace.go:171","msg":"trace[1193630186] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"134.812964ms","start":"2026-06-04T11:54:52.592141Z","end":"2026-06-04T11:54:52.726954Z","steps":["trace[1193630186] 'process raft request' (duration: 134.555899ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:54:52.727063Z","caller":"traceutil/trace.go:171","msg":"trace[698175308] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"134.899375ms","start":"2026-06-04T11:54:52.592127Z","end":"2026-06-04T11:54:52.727026Z","steps":["trace[698175308] 'process raft request' (duration: 134.726972ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:54:52.727347Z","caller":"traceutil/trace.go:171","msg":"trace[1876764197] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"133.929458ms","start":"2026-06-04T11:54:52.593397Z","end":"2026-06-04T11:54:52.727326Z","steps":["trace[1876764197] 'process raft request' (duration: 133.504651ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:54:53.049122Z","caller":"traceutil/trace.go:171","msg":"trace[1523900136] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"127.38327ms","start":"2026-06-04T11:54:52.921711Z","end":"2026-06-04T11:54:53.049095Z","steps":["trace[1523900136] 'process raft request' (duration: 127.160886ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:55:31.651082Z","caller":"traceutil/trace.go:171","msg":"trace[2056390898] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"114.873951ms","start":"2026-06-04T11:55:31.536184Z","end":"2026-06-04T11:55:31.651058Z","steps":["trace[2056390898] 'process raft request' (duration: 114.382294ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:56:23.632139Z","caller":"traceutil/trace.go:171","msg":"trace[501277457] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"191.220991ms","start":"2026-06-04T11:56:23.440869Z","end":"2026-06-04T11:56:23.63209Z","steps":["trace[501277457] 'process raft request' (duration: 190.928607ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:56:55.890138Z","caller":"traceutil/trace.go:171","msg":"trace[369381496] linearizableReadLoop","detail":"{readStateIndex:2782; appliedIndex:2781; }","duration":"104.994707ms","start":"2026-06-04T11:56:55.785111Z","end":"2026-06-04T11:56:55.890105Z","steps":["trace[369381496] 'read index received' (duration: 27.339398ms)","trace[369381496] 'applied index is now lower than readState.Index' (duration: 77.653549ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:56:55.89077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.623465ms","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-06-04T11:56:55.891293Z","caller":"traceutil/trace.go:171","msg":"trace[916015787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2677; }","duration":"106.218343ms","start":"2026-06-04T11:56:55.785052Z","end":"2026-06-04T11:56:55.89127Z","steps":["trace[916015787] 'agreement among raft nodes before linearized reading' (duration: 105.399093ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:56:55.89114Z","caller":"traceutil/trace.go:171","msg":"trace[319925485] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"114.456705ms","start":"2026-06-04T11:56:55.77664Z","end":"2026-06-04T11:56:55.891097Z","steps":["trace[319925485] 'process raft request' (duration: 35.751072ms)","trace[319925485] 'compare' (duration: 77.572929ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:00:40.602606Z","caller":"traceutil/trace.go:171","msg":"trace[33610767] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4626; }","duration":"109.686259ms","start":"2026-06-04T12:00:40.49288Z","end":"2026-06-04T12:00:40.602566Z","steps":["trace[33610767] 'process raft request' (duration: 109.451094ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:40.602524Z","caller":"traceutil/trace.go:171","msg":"trace[895974222] linearizableReadLoop","detail":"{readStateIndex:4805; appliedIndex:4803; }","duration":"103.619243ms","start":"2026-06-04T12:00:40.498881Z","end":"2026-06-04T12:00:40.6025Z","steps":["trace[895974222] 'read index received' (duration: 72.143942ms)","trace[895974222] 'applied index is now lower than readState.Index' (duration: 31.474241ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:00:40.602906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.010471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-04T12:00:40.602954Z","caller":"traceutil/trace.go:171","msg":"trace[1934893066] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4627; }","duration":"104.079793ms","start":"2026-06-04T12:00:40.498849Z","end":"2026-06-04T12:00:40.602929Z","steps":["trace[1934893066] 'agreement among raft nodes before linearized reading' (duration: 103.853428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:00:40.623151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.488019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-04T12:00:40.6235Z","caller":"traceutil/trace.go:171","msg":"trace[1210770392] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4629; }","duration":"115.621691ms","start":"2026-06-04T12:00:40.507618Z","end":"2026-06-04T12:00:40.62324Z","steps":["trace[1210770392] 'agreement among raft nodes before linearized reading' (duration: 114.638101ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:00.070266Z","caller":"traceutil/trace.go:171","msg":"trace[1640607642] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"320.089868ms","start":"2026-06-04T12:00:59.750151Z","end":"2026-06-04T12:01:00.070241Z","steps":["trace[1640607642] 'process raft request' (duration: 319.921995ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:00.070285Z","caller":"traceutil/trace.go:171","msg":"trace[25508454] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5044; }","duration":"281.286327ms","start":"2026-06-04T12:00:59.788966Z","end":"2026-06-04T12:01:00.070252Z","steps":["trace[25508454] 'read index received' (duration: 281.268516ms)","trace[25508454] 'applied index is now lower than readState.Index' (duration: 15.271µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:01:00.070427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:00:59.750131Z","time spent":"320.214142ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:01:00.07064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.655574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:01:00.070735Z","caller":"traceutil/trace.go:171","msg":"trace[1494416724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4857; }","duration":"281.820077ms","start":"2026-06-04T12:00:59.788899Z","end":"2026-06-04T12:01:00.070719Z","steps":["trace[1494416724] 'agreement among raft nodes before linearized reading' (duration: 281.536791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.0852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.886213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:01:00.085256Z","caller":"traceutil/trace.go:171","msg":"trace[780216410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4858; }","duration":"116.999286ms","start":"2026-06-04T12:00:59.968243Z","end":"2026-06-04T12:01:00.085242Z","steps":["trace[780216410] 'agreement among raft nodes before linearized reading' (duration: 116.874403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.085265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.593705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-04T12:01:00.085352Z","caller":"traceutil/trace.go:171","msg":"trace[2053558211] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4858; }","duration":"252.676256ms","start":"2026-06-04T12:00:59.832644Z","end":"2026-06-04T12:01:00.08532Z","steps":["trace[2053558211] 'agreement among raft nodes before linearized reading' (duration: 252.463152ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:00.085427Z","caller":"traceutil/trace.go:171","msg":"trace[391332166] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"222.511156ms","start":"2026-06-04T12:00:59.862906Z","end":"2026-06-04T12:01:00.085417Z","steps":["trace[391332166] 'process raft request' (duration: 222.068807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.085739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.034604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:01:00.085768Z","caller":"traceutil/trace.go:171","msg":"trace[1593702519] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:4858; }","duration":"245.119446ms","start":"2026-06-04T12:00:59.840642Z","end":"2026-06-04T12:01:00.085761Z","steps":["trace[1593702519] 'agreement among raft nodes before linearized reading' (duration: 245.032684ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:00.872143Z","caller":"traceutil/trace.go:171","msg":"trace[1939031178] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"236.827895ms","start":"2026-06-04T12:01:00.635292Z","end":"2026-06-04T12:01:00.872119Z","steps":["trace[1939031178] 'process raft request' (duration: 236.671022ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:00.885686Z","caller":"traceutil/trace.go:171","msg":"trace[1701968245] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5051; }","duration":"127.212938ms","start":"2026-06-04T12:01:00.758443Z","end":"2026-06-04T12:01:00.885656Z","steps":["trace[1701968245] 'read index received' (duration: 113.590317ms)","trace[1701968245] 'applied index is now lower than readState.Index' (duration: 13.62128ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:00.885752Z","caller":"traceutil/trace.go:171","msg":"trace[1034433490] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"208.469532ms","start":"2026-06-04T12:01:00.677254Z","end":"2026-06-04T12:01:00.885723Z","steps":["trace[1034433490] 'process raft request' (duration: 208.236397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.88598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.513633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-06-04T12:01:00.886031Z","caller":"traceutil/trace.go:171","msg":"trace[1816170937] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4866; }","duration":"127.615916ms","start":"2026-06-04T12:01:00.758402Z","end":"2026-06-04T12:01:00.886018Z","steps":["trace[1816170937] 'agreement among raft nodes before linearized reading' (duration: 127.429582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.886079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.530565ms","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-06-04T12:01:00.88612Z","caller":"traceutil/trace.go:171","msg":"trace[2109405103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4866; }","duration":"116.621487ms","start":"2026-06-04T12:01:00.769489Z","end":"2026-06-04T12:01:00.886111Z","steps":["trace[2109405103] 'agreement among raft nodes before linearized reading' (duration: 116.438273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:00.886314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.714029ms","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-06-04T12:01:00.886431Z","caller":"traceutil/trace.go:171","msg":"trace[1722261656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4866; }","duration":"116.857901ms","start":"2026-06-04T12:01:00.769554Z","end":"2026-06-04T12:01:00.886412Z","steps":["trace[1722261656] 'agreement among raft nodes before linearized reading' (duration: 116.451543ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:01.013271Z","caller":"traceutil/trace.go:171","msg":"trace[1809841366] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"123.472393ms","start":"2026-06-04T12:01:00.889769Z","end":"2026-06-04T12:01:01.013241Z","steps":["trace[1809841366] 'process raft request' (duration: 74.548401ms)","trace[1809841366] 'compare' (duration: 48.786269ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:01.020611Z","caller":"traceutil/trace.go:171","msg":"trace[1447107989] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"130.263257ms","start":"2026-06-04T12:01:00.890332Z","end":"2026-06-04T12:01:01.020596Z","steps":["trace[1447107989] 'process raft request' (duration: 130.138614ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:01.020907Z","caller":"traceutil/trace.go:171","msg":"trace[1548219709] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"117.506123ms","start":"2026-06-04T12:01:00.903377Z","end":"2026-06-04T12:01:01.020884Z","steps":["trace[1548219709] 'process raft request' (duration: 117.170667ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:01.021042Z","caller":"traceutil/trace.go:171","msg":"trace[1424338818] linearizableReadLoop","detail":"{readStateIndex:5055; appliedIndex:5053; }","duration":"121.026284ms","start":"2026-06-04T12:01:00.899999Z","end":"2026-06-04T12:01:01.021025Z","steps":["trace[1424338818] 'read index received' (duration: 64.330529ms)","trace[1424338818] 'applied index is now lower than readState.Index' (duration: 56.694735ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:01.021155Z","caller":"traceutil/trace.go:171","msg":"trace[1487379823] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"109.71905ms","start":"2026-06-04T12:01:00.911422Z","end":"2026-06-04T12:01:01.021141Z","steps":["trace[1487379823] 'process raft request' (duration: 109.460474ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:01.021208Z","caller":"traceutil/trace.go:171","msg":"trace[1855279827] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"111.019375ms","start":"2026-06-04T12:01:00.910175Z","end":"2026-06-04T12:01:01.021194Z","steps":["trace[1855279827] 'process raft request' (duration: 110.604856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:01.021685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.662967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e1f77d36-89be-49fc-8aa5-92c9ab224a32\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-06-04T12:01:01.021757Z","caller":"traceutil/trace.go:171","msg":"trace[1733885752] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e1f77d36-89be-49fc-8aa5-92c9ab224a32; range_end:; response_count:1; response_revision:4872; }","duration":"121.767308ms","start":"2026-06-04T12:01:00.89997Z","end":"2026-06-04T12:01:01.021737Z","steps":["trace[1733885752] 'agreement among raft nodes before linearized reading' (duration: 121.583205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:01.270405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.021172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-04T12:01:01.270567Z","caller":"traceutil/trace.go:171","msg":"trace[288068751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4874; }","duration":"102.222417ms","start":"2026-06-04T12:01:01.168316Z","end":"2026-06-04T12:01:01.270538Z","steps":["trace[288068751] 'range keys from in-memory index tree' (duration: 101.717726ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:43.349435Z","caller":"traceutil/trace.go:171","msg":"trace[285469366] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"113.390406ms","start":"2026-06-04T12:01:43.236015Z","end":"2026-06-04T12:01:43.349406Z","steps":["trace[285469366] 'process raft request' (duration: 47.520038ms)","trace[285469366] 'compare' (duration: 65.695124ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:43.349649Z","caller":"traceutil/trace.go:171","msg":"trace[1576201623] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"110.202857ms","start":"2026-06-04T12:01:43.239413Z","end":"2026-06-04T12:01:43.349616Z","steps":["trace[1576201623] 'process raft request' (duration: 109.954342ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:32.493758Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2872} {"level":"info","ts":"2026-06-04T12:02:32.589142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2872,"took":"94.090657ms","hash":866724849,"current-db-size-bytes":54284288,"current-db-size":"54 MB","current-db-size-in-use-bytes":29642752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:02:32.589214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866724849,"revision":2872,"compact-revision":-1} {"level":"info","ts":"2026-06-04T12:07:32.501159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5614} {"level":"info","ts":"2026-06-04T12:07:32.634385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5614,"took":"132.05001ms","hash":2062907413,"current-db-size-bytes":54284288,"current-db-size":"54 MB","current-db-size-in-use-bytes":18276352,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-04T12:07:32.634435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2062907413,"revision":5614,"compact-revision":2872}