{"level":"warn","ts":"2026-04-11T17:49:31.104677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:49:31.104756Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-04-11T17:49:31.104823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:49:31.104837Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-11T17:49:31.104854Z","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-04-11T17:49:31.105534Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-04-11T17:49:31.105612Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-04-11T17:49:31.109114Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.048679ms"} {"level":"info","ts":"2026-04-11T17:49:31.12538Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-04-11T17:49:31.125487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:49:31.125515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:49:31.125526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:49:31.125539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:49:31.125579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-04-11T17:49:31.131012Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:49:31.134668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:49:31.136328Z","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-04-11T17:49:31.139634Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:49:31.139836Z","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-04-11T17:49:31.139977Z","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-04-11T17:49:31.139998Z","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-04-11T17:49:31.139981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:49:31.141086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-04-11T17:49:31.141198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-11T17:49:31.143361Z","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-04-11T17:49:31.143496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-11T17:49:31.143595Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-11T17:49:31.143827Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:49:31.14391Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:49:31.426296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:49:31.426389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:49:31.42643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-04-11T17:49:31.426457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:49:31.426525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-11T17:49:31.426546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:49:31.426585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-11T17:49:31.427636Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:49:31.42772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:49:31.427928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:49:31.428311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:49:31.42834Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:49:31.428358Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:49:31.43063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:49:31.430882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:49:31.43093Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:49:31.43172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-04-11T17:49:31.434163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:49:33.207434Z","caller":"traceutil/trace.go:171","msg":"trace[1232802281] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"184.079963ms","start":"2026-04-11T17:49:33.023294Z","end":"2026-04-11T17:49:33.207374Z","steps":["trace[1232802281] 'process raft request' (duration: 175.645851ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:33.20737Z","caller":"traceutil/trace.go:171","msg":"trace[721458096] linearizableReadLoop","detail":"{readStateIndex:112; appliedIndex:111; }","duration":"183.873599ms","start":"2026-04-11T17:49:33.02347Z","end":"2026-04-11T17:49:33.207343Z","steps":["trace[721458096] 'read index received' (duration: 175.386126ms)","trace[721458096] 'applied index is now lower than readState.Index' (duration: 8.486563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:33.207644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.083763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:49:33.207759Z","caller":"traceutil/trace.go:171","msg":"trace[1871335150] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:108; }","duration":"184.305558ms","start":"2026-04-11T17:49:33.023438Z","end":"2026-04-11T17:49:33.207744Z","steps":["trace[1871335150] 'agreement among raft nodes before linearized reading' (duration: 184.051263ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:44.680951Z","caller":"traceutil/trace.go:171","msg":"trace[920849757] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"198.296297ms","start":"2026-04-11T17:49:44.482621Z","end":"2026-04-11T17:49:44.680917Z","steps":["trace[920849757] 'read index received' (duration: 198.038042ms)","trace[920849757] 'applied index is now lower than readState.Index' (duration: 256.535µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:44.681081Z","caller":"traceutil/trace.go:171","msg":"trace[541269818] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"226.070629ms","start":"2026-04-11T17:49:44.454959Z","end":"2026-04-11T17:49:44.681029Z","steps":["trace[541269818] 'process raft request' (duration: 225.679702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:44.681196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.082043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:44.681201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.574322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T17:49:44.68126Z","caller":"traceutil/trace.go:171","msg":"trace[1703200576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"171.177746ms","start":"2026-04-11T17:49:44.510063Z","end":"2026-04-11T17:49:44.681241Z","steps":["trace[1703200576] 'agreement among raft nodes before linearized reading' (duration: 171.084024ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:44.681269Z","caller":"traceutil/trace.go:171","msg":"trace[1363770619] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:401; }","duration":"198.744345ms","start":"2026-04-11T17:49:44.482507Z","end":"2026-04-11T17:49:44.681252Z","steps":["trace[1363770619] 'agreement among raft nodes before linearized reading' (duration: 198.578542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:45.032391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.797712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:45.032592Z","caller":"traceutil/trace.go:171","msg":"trace[1886892432] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"293.056754ms","start":"2026-04-11T17:49:44.739519Z","end":"2026-04-11T17:49:45.032576Z","steps":["trace[1886892432] 'read index received' (duration: 174.444877ms)","trace[1886892432] 'applied index is now lower than readState.Index' (duration: 118.610927ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:45.032691Z","caller":"traceutil/trace.go:171","msg":"trace[111473219] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"313.807973ms","start":"2026-04-11T17:49:44.718874Z","end":"2026-04-11T17:49:45.032682Z","steps":["trace[111473219] 'process raft request' (duration: 313.62839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:45.032775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:44.718842Z","time spent":"313.898125ms","remote":"127.0.0.1:57222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:45.032768Z","caller":"traceutil/trace.go:171","msg":"trace[472936832] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"317.748819ms","start":"2026-04-11T17:49:44.71496Z","end":"2026-04-11T17:49:45.032709Z","steps":["trace[472936832] 'process raft request' (duration: 199.095801ms)","trace[472936832] 'compare' (duration: 117.656449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:45.032895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.328109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-11T17:49:45.033016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:44.714948Z","time spent":"317.944762ms","remote":"127.0.0.1:57278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:45.033031Z","caller":"traceutil/trace.go:171","msg":"trace[17829068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"182.540933ms","start":"2026-04-11T17:49:44.85046Z","end":"2026-04-11T17:49:45.033001Z","steps":["trace[17829068] 'agreement among raft nodes before linearized reading' (duration: 182.295649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:45.032939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.383871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-11T17:49:45.033137Z","caller":"traceutil/trace.go:171","msg":"trace[97198840] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:404; }","duration":"293.635496ms","start":"2026-04-11T17:49:44.739482Z","end":"2026-04-11T17:49:45.033117Z","steps":["trace[97198840] 'agreement among raft nodes before linearized reading' (duration: 293.306849ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:45.176767Z","caller":"traceutil/trace.go:171","msg":"trace[149893731] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"137.905306ms","start":"2026-04-11T17:49:45.038819Z","end":"2026-04-11T17:49:45.176724Z","steps":["trace[149893731] 'process raft request' (duration: 115.656639ms)","trace[149893731] 'compare' (duration: 21.947442ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:02.424451Z","caller":"traceutil/trace.go:171","msg":"trace[2004558690] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"109.42497ms","start":"2026-04-11T17:50:02.314968Z","end":"2026-04-11T17:50:02.424393Z","steps":["trace[2004558690] 'process raft request' (duration: 105.916863ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:02.426941Z","caller":"traceutil/trace.go:171","msg":"trace[1902765666] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"110.878918ms","start":"2026-04-11T17:50:02.316028Z","end":"2026-04-11T17:50:02.426907Z","steps":["trace[1902765666] 'process raft request' (duration: 110.789196ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:09.45029Z","caller":"traceutil/trace.go:171","msg":"trace[1980848918] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"132.849612ms","start":"2026-04-11T17:50:09.317414Z","end":"2026-04-11T17:50:09.450263Z","steps":["trace[1980848918] 'read index received' (duration: 132.694979ms)","trace[1980848918] 'applied index is now lower than readState.Index' (duration: 153.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:09.450427Z","caller":"traceutil/trace.go:171","msg":"trace[1453424958] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"288.936053ms","start":"2026-04-11T17:50:09.161482Z","end":"2026-04-11T17:50:09.450418Z","steps":["trace[1453424958] 'process raft request' (duration: 288.617187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:09.450566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.631922ms","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-04-11T17:50:09.450673Z","caller":"traceutil/trace.go:171","msg":"trace[1007608956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"128.774335ms","start":"2026-04-11T17:50:09.32188Z","end":"2026-04-11T17:50:09.450654Z","steps":["trace[1007608956] 'agreement among raft nodes before linearized reading' (duration: 128.573151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:09.451088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.645816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:50:09.45117Z","caller":"traceutil/trace.go:171","msg":"trace[1111750812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"133.783139ms","start":"2026-04-11T17:50:09.317365Z","end":"2026-04-11T17:50:09.451149Z","steps":["trace[1111750812] 'agreement among raft nodes before linearized reading' (duration: 133.365261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.108459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.823101ms","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-04-11T17:51:40.10856Z","caller":"traceutil/trace.go:171","msg":"trace[1126179015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"175.976934ms","start":"2026-04-11T17:51:39.932563Z","end":"2026-04-11T17:51:40.10854Z","steps":["trace[1126179015] 'range keys from in-memory index tree' (duration: 175.599218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.108574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.200429ms","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-04-11T17:51:40.108677Z","caller":"traceutil/trace.go:171","msg":"trace[152848170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"167.339521ms","start":"2026-04-11T17:51:39.941302Z","end":"2026-04-11T17:51:40.108641Z","steps":["trace[152848170] 'range keys from in-memory index tree' (duration: 167.027086ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:40.184928Z","caller":"traceutil/trace.go:171","msg":"trace[1455837487] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"220.433285ms","start":"2026-04-11T17:51:39.964468Z","end":"2026-04-11T17:51:40.184902Z","steps":["trace[1455837487] 'read index received' (duration: 220.260391ms)","trace[1455837487] 'applied index is now lower than readState.Index' (duration: 171.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:40.185043Z","caller":"traceutil/trace.go:171","msg":"trace[1933433715] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"233.224107ms","start":"2026-04-11T17:51:39.951808Z","end":"2026-04-11T17:51:40.185032Z","steps":["trace[1933433715] 'process raft request' (duration: 232.84543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.18541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.920173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:51:40.185535Z","caller":"traceutil/trace.go:171","msg":"trace[936668096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"221.091298ms","start":"2026-04-11T17:51:39.964424Z","end":"2026-04-11T17:51:40.185515Z","steps":["trace[936668096] 'agreement among raft nodes before linearized reading' (duration: 220.812793ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:40.291059Z","caller":"traceutil/trace.go:171","msg":"trace[232388741] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"177.898971ms","start":"2026-04-11T17:51:40.113128Z","end":"2026-04-11T17:51:40.291027Z","steps":["trace[232388741] 'process raft request' (duration: 177.680537ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:40.291313Z","caller":"traceutil/trace.go:171","msg":"trace[725351493] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1922; }","duration":"106.286369ms","start":"2026-04-11T17:51:40.184994Z","end":"2026-04-11T17:51:40.291281Z","steps":["trace[725351493] 'read index received' (duration: 105.83632ms)","trace[725351493] 'applied index is now lower than readState.Index' (duration: 448.419µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:40.291612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.942786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:51:40.291709Z","caller":"traceutil/trace.go:171","msg":"trace[868262206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"327.097889ms","start":"2026-04-11T17:51:39.964598Z","end":"2026-04-11T17:51:40.291696Z","steps":["trace[868262206] 'agreement among raft nodes before linearized reading' (duration: 326.836885ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:40.291644Z","caller":"traceutil/trace.go:171","msg":"trace[1476006627] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"177.623416ms","start":"2026-04-11T17:51:40.114002Z","end":"2026-04-11T17:51:40.291626Z","steps":["trace[1476006627] 'process raft request' (duration: 176.979114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.291863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:39.964573Z","time spent":"327.263263ms","remote":"127.0.0.1:57278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:40.291734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.034488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cb0b4ad-168b-4df3-9ff4-17fd67ac8e7e\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-11T17:51:40.291597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.714233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:40.292195Z","caller":"traceutil/trace.go:171","msg":"trace[1038600102] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cb0b4ad-168b-4df3-9ff4-17fd67ac8e7e; range_end:; response_count:1; response_revision:1852; }","duration":"310.510258ms","start":"2026-04-11T17:51:39.981646Z","end":"2026-04-11T17:51:40.292156Z","steps":["trace[1038600102] 'agreement among raft nodes before linearized reading' (duration: 310.012378ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:40.292257Z","caller":"traceutil/trace.go:171","msg":"trace[676832821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1852; }","duration":"309.408736ms","start":"2026-04-11T17:51:39.982828Z","end":"2026-04-11T17:51:40.292236Z","steps":["trace[676832821] 'agreement among raft nodes before linearized reading' (duration: 308.700502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.292364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.299174ms","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-04-11T17:51:40.292435Z","caller":"traceutil/trace.go:171","msg":"trace[1893882257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"327.391477ms","start":"2026-04-11T17:51:39.965031Z","end":"2026-04-11T17:51:40.292423Z","steps":["trace[1893882257] 'agreement among raft nodes before linearized reading' (duration: 326.732093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:40.292467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:39.96502Z","time spent":"327.438317ms","remote":"127.0.0.1:57278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:40.292455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:39.982816Z","time spent":"309.60652ms","remote":"127.0.0.1:53370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:51:40.292524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:39.981619Z","time spent":"310.63952ms","remote":"127.0.0.1:57222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cb0b4ad-168b-4df3-9ff4-17fd67ac8e7e\" "} {"level":"info","ts":"2026-04-11T17:51:40.306788Z","caller":"traceutil/trace.go:171","msg":"trace[956638488] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"116.526512ms","start":"2026-04-11T17:51:40.190225Z","end":"2026-04-11T17:51:40.306752Z","steps":["trace[956638488] 'process raft request' (duration: 116.276067ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:54.473092Z","caller":"traceutil/trace.go:171","msg":"trace[161873706] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"121.509056ms","start":"2026-04-11T17:51:54.351558Z","end":"2026-04-11T17:51:54.473067Z","steps":["trace[161873706] 'read index received' (duration: 121.19961ms)","trace[161873706] 'applied index is now lower than readState.Index' (duration: 308.336µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:54.47321Z","caller":"traceutil/trace.go:171","msg":"trace[713099877] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"239.453474ms","start":"2026-04-11T17:51:54.233726Z","end":"2026-04-11T17:51:54.473179Z","steps":["trace[713099877] 'process raft request' (duration: 239.117718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:54.473323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.74943ms","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-04-11T17:51:54.473359Z","caller":"traceutil/trace.go:171","msg":"trace[2056168085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1969; }","duration":"121.833272ms","start":"2026-04-11T17:51:54.351516Z","end":"2026-04-11T17:51:54.473349Z","steps":["trace[2056168085] 'agreement among raft nodes before linearized reading' (duration: 121.68899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:54.47345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.925545ms","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-04-11T17:51:54.473509Z","caller":"traceutil/trace.go:171","msg":"trace[2096912576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1969; }","duration":"121.036267ms","start":"2026-04-11T17:51:54.352457Z","end":"2026-04-11T17:51:54.473493Z","steps":["trace[2096912576] 'agreement among raft nodes before linearized reading' (duration: 120.819463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:54.473614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.421899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:51:54.473696Z","caller":"traceutil/trace.go:171","msg":"trace[1572057437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1969; }","duration":"117.528601ms","start":"2026-04-11T17:51:54.35615Z","end":"2026-04-11T17:51:54.473679Z","steps":["trace[1572057437] 'agreement among raft nodes before linearized reading' (duration: 117.321548ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:24.806883Z","caller":"traceutil/trace.go:171","msg":"trace[1742207695] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"156.994102ms","start":"2026-04-11T17:52:24.649855Z","end":"2026-04-11T17:52:24.80685Z","steps":["trace[1742207695] 'process raft request' (duration: 119.609731ms)","trace[1742207695] 'compare' (duration: 37.121657ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:24.806881Z","caller":"traceutil/trace.go:171","msg":"trace[621488297] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"156.749638ms","start":"2026-04-11T17:52:24.6501Z","end":"2026-04-11T17:52:24.806849Z","steps":["trace[621488297] 'process raft request' (duration: 156.654577ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:24.807073Z","caller":"traceutil/trace.go:171","msg":"trace[461642402] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2232; }","duration":"157.005983ms","start":"2026-04-11T17:52:24.650032Z","end":"2026-04-11T17:52:24.807038Z","steps":["trace[461642402] 'read index received' (duration: 75.055236ms)","trace[461642402] 'applied index is now lower than readState.Index' (duration: 81.948287ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:24.807131Z","caller":"traceutil/trace.go:171","msg":"trace[700100351] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"155.451134ms","start":"2026-04-11T17:52:24.651654Z","end":"2026-04-11T17:52:24.807105Z","steps":["trace[700100351] 'process raft request' (duration: 155.150689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:24.807322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.265207ms","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-04-11T17:52:24.807375Z","caller":"traceutil/trace.go:171","msg":"trace[133821324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2154; }","duration":"157.36857ms","start":"2026-04-11T17:52:24.649993Z","end":"2026-04-11T17:52:24.807362Z","steps":["trace[133821324] 'agreement among raft nodes before linearized reading' (duration: 157.192376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:31.012358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.120847ms","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-04-11T17:52:31.012433Z","caller":"traceutil/trace.go:171","msg":"trace[1283711913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2184; }","duration":"131.24228ms","start":"2026-04-11T17:52:30.881177Z","end":"2026-04-11T17:52:31.01242Z","steps":["trace[1283711913] 'range keys from in-memory index tree' (duration: 130.966224ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:31.165877Z","caller":"traceutil/trace.go:171","msg":"trace[1750864012] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"149.518702ms","start":"2026-04-11T17:52:31.016334Z","end":"2026-04-11T17:52:31.165853Z","steps":["trace[1750864012] 'process raft request' (duration: 149.290788ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:36.83443Z","caller":"traceutil/trace.go:171","msg":"trace[1068271749] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"107.870473ms","start":"2026-04-11T17:52:36.726517Z","end":"2026-04-11T17:52:36.834387Z","steps":["trace[1068271749] 'process raft request' (duration: 107.532957ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:59.909631Z","caller":"traceutil/trace.go:171","msg":"trace[437725404] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"121.855357ms","start":"2026-04-11T17:53:59.787736Z","end":"2026-04-11T17:53:59.909591Z","steps":["trace[437725404] 'process raft request' (duration: 121.581802ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:51.077945Z","caller":"traceutil/trace.go:171","msg":"trace[968910756] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"218.058207ms","start":"2026-04-11T17:57:50.85986Z","end":"2026-04-11T17:57:51.077918Z","steps":["trace[968910756] 'read index received' (duration: 217.903634ms)","trace[968910756] 'applied index is now lower than readState.Index' (duration: 153.333µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:57:51.077993Z","caller":"traceutil/trace.go:171","msg":"trace[1580948995] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"245.316454ms","start":"2026-04-11T17:57:50.832642Z","end":"2026-04-11T17:57:51.077958Z","steps":["trace[1580948995] 'process raft request' (duration: 245.09346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:57:51.078152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.411338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:57:51.078168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.294963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c34b0157-4ed0-4687-9c28-ce76c67d41d7\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-11T17:57:51.078199Z","caller":"traceutil/trace.go:171","msg":"trace[2104931916] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5003; }","duration":"175.518919ms","start":"2026-04-11T17:57:50.902668Z","end":"2026-04-11T17:57:51.078187Z","steps":["trace[2104931916] 'agreement among raft nodes before linearized reading' (duration: 175.412807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:51.078209Z","caller":"traceutil/trace.go:171","msg":"trace[2039549102] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c34b0157-4ed0-4687-9c28-ce76c67d41d7; range_end:; response_count:1; response_revision:5003; }","duration":"218.395744ms","start":"2026-04-11T17:57:50.859802Z","end":"2026-04-11T17:57:51.078198Z","steps":["trace[2039549102] 'agreement among raft nodes before linearized reading' (duration: 218.22684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:57:51.078248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.678959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:57:51.078306Z","caller":"traceutil/trace.go:171","msg":"trace[775401952] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5003; }","duration":"163.752741ms","start":"2026-04-11T17:57:50.914535Z","end":"2026-04-11T17:57:51.078288Z","steps":["trace[775401952] 'agreement among raft nodes before linearized reading' (duration: 163.658809ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:51.454956Z","caller":"traceutil/trace.go:171","msg":"trace[865186866] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"177.22204ms","start":"2026-04-11T17:57:51.277704Z","end":"2026-04-11T17:57:51.454926Z","steps":["trace[865186866] 'process raft request' (duration: 169.235702ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:02.855556Z","caller":"traceutil/trace.go:171","msg":"trace[149638967] linearizableReadLoop","detail":"{readStateIndex:5433; appliedIndex:5432; }","duration":"265.280915ms","start":"2026-04-11T17:58:02.59025Z","end":"2026-04-11T17:58:02.855531Z","steps":["trace[149638967] 'read index received' (duration: 265.134713ms)","trace[149638967] 'applied index is now lower than readState.Index' (duration: 145.042µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:58:02.855622Z","caller":"traceutil/trace.go:171","msg":"trace[1466281687] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"295.570427ms","start":"2026-04-11T17:58:02.560016Z","end":"2026-04-11T17:58:02.855586Z","steps":["trace[1466281687] 'process raft request' (duration: 295.349363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:58:02.855827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.56686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-11T17:58:02.855884Z","caller":"traceutil/trace.go:171","msg":"trace[1727756839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5233; }","duration":"265.669742ms","start":"2026-04-11T17:58:02.5902Z","end":"2026-04-11T17:58:02.855869Z","steps":["trace[1727756839] 'agreement among raft nodes before linearized reading' (duration: 265.458468ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:02.856295Z","caller":"traceutil/trace.go:171","msg":"trace[114260572] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"113.560338ms","start":"2026-04-11T17:58:02.742711Z","end":"2026-04-11T17:58:02.856271Z","steps":["trace[114260572] 'process raft request' (duration: 113.394765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:58:02.856334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.707184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-11T17:58:02.856367Z","caller":"traceutil/trace.go:171","msg":"trace[1656980857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5234; }","duration":"111.782556ms","start":"2026-04-11T17:58:02.744575Z","end":"2026-04-11T17:58:02.856357Z","steps":["trace[1656980857] 'agreement among raft nodes before linearized reading' (duration: 111.647534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:58:02.856472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.341513ms","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-04-11T17:58:02.856519Z","caller":"traceutil/trace.go:171","msg":"trace[1306739208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5234; }","duration":"108.425564ms","start":"2026-04-11T17:58:02.748082Z","end":"2026-04-11T17:58:02.856508Z","steps":["trace[1306739208] 'agreement among raft nodes before linearized reading' (duration: 108.323642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:58:02.856495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.195141ms","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-04-11T17:58:02.856576Z","caller":"traceutil/trace.go:171","msg":"trace[1328905812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5234; }","duration":"107.305993ms","start":"2026-04-11T17:58:02.749248Z","end":"2026-04-11T17:58:02.856554Z","steps":["trace[1328905812] 'agreement among raft nodes before linearized reading' (duration: 107.067658ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.247251Z","caller":"traceutil/trace.go:171","msg":"trace[1851969538] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"111.712993ms","start":"2026-04-11T17:58:10.135512Z","end":"2026-04-11T17:58:10.247225Z","steps":["trace[1851969538] 'process raft request' (duration: 111.504039ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.303807Z","caller":"traceutil/trace.go:171","msg":"trace[1257364418] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"109.8653ms","start":"2026-04-11T17:58:10.193914Z","end":"2026-04-11T17:58:10.30378Z","steps":["trace[1257364418] 'process raft request' (duration: 109.643276ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.400743Z","caller":"traceutil/trace.go:171","msg":"trace[721055045] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"147.321635ms","start":"2026-04-11T17:58:10.253393Z","end":"2026-04-11T17:58:10.400714Z","steps":["trace[721055045] 'process raft request' (duration: 147.103431ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.400778Z","caller":"traceutil/trace.go:171","msg":"trace[665082078] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"147.311325ms","start":"2026-04-11T17:58:10.253432Z","end":"2026-04-11T17:58:10.400743Z","steps":["trace[665082078] 'process raft request' (duration: 147.111491ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.400787Z","caller":"traceutil/trace.go:171","msg":"trace[1548118580] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"147.516228ms","start":"2026-04-11T17:58:10.253249Z","end":"2026-04-11T17:58:10.400765Z","steps":["trace[1548118580] 'process raft request' (duration: 86.625778ms)","trace[1548118580] 'compare' (duration: 60.466943ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:58:10.400841Z","caller":"traceutil/trace.go:171","msg":"trace[66172005] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"146.919237ms","start":"2026-04-11T17:58:10.253906Z","end":"2026-04-11T17:58:10.400825Z","steps":["trace[66172005] 'process raft request' (duration: 146.710304ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.400748Z","caller":"traceutil/trace.go:171","msg":"trace[2131659229] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"147.174772ms","start":"2026-04-11T17:58:10.253538Z","end":"2026-04-11T17:58:10.400712Z","steps":["trace[2131659229] 'process raft request' (duration: 147.03629ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.400715Z","caller":"traceutil/trace.go:171","msg":"trace[587366223] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"146.641053ms","start":"2026-04-11T17:58:10.254049Z","end":"2026-04-11T17:58:10.40069Z","steps":["trace[587366223] 'process raft request' (duration: 146.601522ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:58:10.433916Z","caller":"traceutil/trace.go:171","msg":"trace[349215946] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"122.213609ms","start":"2026-04-11T17:58:10.31168Z","end":"2026-04-11T17:58:10.433894Z","steps":["trace[349215946] 'process raft request' (duration: 122.050336ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:59:31.468872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3027} {"level":"info","ts":"2026-04-11T17:59:31.547839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3027,"took":"77.368045ms","hash":2697384439,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":31760384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-11T17:59:31.54791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697384439,"revision":3027,"compact-revision":-1} {"level":"info","ts":"2026-04-11T18:04:18.123284Z","caller":"traceutil/trace.go:171","msg":"trace[649786794] linearizableReadLoop","detail":"{readStateIndex:8535; appliedIndex:8534; }","duration":"112.106382ms","start":"2026-04-11T18:04:18.011147Z","end":"2026-04-11T18:04:18.123253Z","steps":["trace[649786794] 'read index received' (duration: 111.839477ms)","trace[649786794] 'applied index is now lower than readState.Index' (duration: 265.795µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:04:18.123405Z","caller":"traceutil/trace.go:171","msg":"trace[499594543] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"196.771851ms","start":"2026-04-11T18:04:17.926588Z","end":"2026-04-11T18:04:18.12336Z","steps":["trace[499594543] 'process raft request' (duration: 196.474856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:04:18.123705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.519439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q4pnv\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-11T18:04:18.123757Z","caller":"traceutil/trace.go:171","msg":"trace[469404781] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q4pnv; range_end:; response_count:1; response_revision:8243; }","duration":"112.635651ms","start":"2026-04-11T18:04:18.011109Z","end":"2026-04-11T18:04:18.123745Z","steps":["trace[469404781] 'agreement among raft nodes before linearized reading' (duration: 112.271924ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:31.176118Z","caller":"traceutil/trace.go:171","msg":"trace[1382014290] linearizableReadLoop","detail":"{readStateIndex:8608; appliedIndex:8607; }","duration":"158.745742ms","start":"2026-04-11T18:04:31.017352Z","end":"2026-04-11T18:04:31.176098Z","steps":["trace[1382014290] 'read index received' (duration: 158.525428ms)","trace[1382014290] 'applied index is now lower than readState.Index' (duration: 219.714µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:04:31.176392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.973819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T18:04:31.176428Z","caller":"traceutil/trace.go:171","msg":"trace[1386929783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8312; }","duration":"120.079991ms","start":"2026-04-11T18:04:31.056337Z","end":"2026-04-11T18:04:31.176417Z","steps":["trace[1386929783] 'agreement among raft nodes before linearized reading' (duration: 119.877867ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:31.176394Z","caller":"traceutil/trace.go:171","msg":"trace[94486157] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"221.612925ms","start":"2026-04-11T18:04:30.954748Z","end":"2026-04-11T18:04:31.176361Z","steps":["trace[94486157] 'process raft request' (duration: 221.189677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:04:31.176458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.095439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-11T18:04:31.176533Z","caller":"traceutil/trace.go:171","msg":"trace[2071105056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8312; }","duration":"159.178161ms","start":"2026-04-11T18:04:31.017335Z","end":"2026-04-11T18:04:31.176513Z","steps":["trace[2071105056] 'agreement among raft nodes before linearized reading' (duration: 158.890646ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:31.502698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6148} {"level":"info","ts":"2026-04-11T18:04:31.602836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6148,"took":"97.414788ms","hash":2289086430,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":23113728,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-11T18:04:31.602906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2289086430,"revision":6148,"compact-revision":3027} {"level":"info","ts":"2026-04-11T18:04:59.037151Z","caller":"traceutil/trace.go:171","msg":"trace[644222249] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"256.209251ms","start":"2026-04-11T18:04:58.780916Z","end":"2026-04-11T18:04:59.037125Z","steps":["trace[644222249] 'process raft request' (duration: 256.048487ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:59.085522Z","caller":"traceutil/trace.go:171","msg":"trace[2041654111] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"158.06771ms","start":"2026-04-11T18:04:58.927427Z","end":"2026-04-11T18:04:59.085494Z","steps":["trace[2041654111] 'process raft request' (duration: 157.928557ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:01.552928Z","caller":"traceutil/trace.go:171","msg":"trace[1060459142] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"118.96958ms","start":"2026-04-11T18:05:01.433935Z","end":"2026-04-11T18:05:01.552905Z","steps":["trace[1060459142] 'process raft request' (duration: 118.890008ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:01.553045Z","caller":"traceutil/trace.go:171","msg":"trace[1829811862] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"120.796744ms","start":"2026-04-11T18:05:01.432221Z","end":"2026-04-11T18:05:01.553018Z","steps":["trace[1829811862] 'process raft request' (duration: 91.947716ms)","trace[1829811862] 'compare' (duration: 28.458821ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:05:02.090323Z","caller":"traceutil/trace.go:171","msg":"trace[1340234670] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"210.895164ms","start":"2026-04-11T18:05:01.879401Z","end":"2026-04-11T18:05:02.090296Z","steps":["trace[1340234670] 'process raft request' (duration: 210.717641ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:12.457446Z","caller":"traceutil/trace.go:171","msg":"trace[443213722] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"105.221714ms","start":"2026-04-11T18:05:12.352172Z","end":"2026-04-11T18:05:12.457394Z","steps":["trace[443213722] 'process raft request' (duration: 104.916858ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:59.800226Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249c.wal"} {"level":"info","ts":"2026-04-11T18:07:06.812939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T18:07:06.817797Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T18:07:06.817875Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T18:09:31.508822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8316} {"level":"info","ts":"2026-04-11T18:09:31.588691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8316,"took":"76.884093ms","hash":45723735,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":24940544,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-11T18:09:31.588772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45723735,"revision":8316,"compact-revision":6148} {"level":"info","ts":"2026-04-11T18:14:31.51592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10715} {"level":"info","ts":"2026-04-11T18:14:31.591557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10715,"took":"72.506228ms","hash":2074207593,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":20672512,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-11T18:14:31.591608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2074207593,"revision":10715,"compact-revision":8316}