{"level":"warn","ts":"2026-07-24T17:20:57.377419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:20:57.3775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-24T17:20:57.377562Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:20:57.377574Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-24T17:20:57.377591Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T17:20:57.378177Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-24T17:20:57.378249Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-24T17:20:57.382562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.861492ms"} {"level":"info","ts":"2026-07-24T17:20:57.390027Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-24T17:20:57.390171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T17:20:57.390208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-24T17:20:57.39022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T17:20:57.390227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-24T17:20:57.390347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-24T17:20:57.396187Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T17:20:57.398331Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T17:20:57.399779Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T17:20:57.402107Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T17:20:57.402328Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:20:57.40241Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:20:57.40242Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:20:57.402506Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T17:20:57.404725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-24T17:20:57.404941Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-24T17:20:57.408136Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T17:20:57.408495Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T17:20:57.408585Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T17:20:57.408794Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-24T17:20:57.408853Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-24T17:20:58.39112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T17:20:58.391198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T17:20:58.391245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-24T17:20:58.391266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-24T17:20:58.391284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-24T17:20:58.391302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-24T17:20:58.391322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-24T17:20:58.392361Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:20:58.393366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T17:20:58.393562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:20:58.393597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:20:58.395992Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T17:20:58.396051Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T17:20:58.397149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:20:58.397317Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:20:58.397357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:20:58.397383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T17:20:58.397714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-07-24T17:21:00.036612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.263844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-24T17:21:00.036706Z","caller":"traceutil/trace.go:171","msg":"trace[776611469] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"289.854472ms","start":"2026-07-24T17:20:59.74684Z","end":"2026-07-24T17:21:00.036694Z","steps":["trace[776611469] 'read index received' (duration: 144.112203ms)","trace[776611469] 'applied index is now lower than readState.Index' (duration: 145.741369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:21:00.036808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.608792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.036833Z","caller":"traceutil/trace.go:171","msg":"trace[396631123] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"325.661433ms","start":"2026-07-24T17:20:59.711165Z","end":"2026-07-24T17:21:00.036827Z","steps":["trace[396631123] 'agreement among raft nodes before linearized reading' (duration: 325.592501ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.03678Z","caller":"traceutil/trace.go:171","msg":"trace[61629286] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"338.182587ms","start":"2026-07-24T17:20:59.698577Z","end":"2026-07-24T17:21:00.036759Z","steps":["trace[61629286] 'process raft request' (duration: 192.360077ms)","trace[61629286] 'compare' (duration: 145.158313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:21:00.036857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:20:59.711149Z","time spent":"325.702023ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-24T17:21:00.036948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.327699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.037035Z","caller":"traceutil/trace.go:171","msg":"trace[617428998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:3; }","duration":"340.28372ms","start":"2026-07-24T17:20:59.696731Z","end":"2026-07-24T17:21:00.037014Z","steps":["trace[617428998] 'agreement among raft nodes before linearized reading' (duration: 340.097429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.037059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:20:59.698558Z","time spent":"338.333859ms","remote":"127.0.0.1:48756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:21:00.037085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:20:59.696723Z","time spent":"340.353451ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T17:21:00.037839Z","caller":"traceutil/trace.go:171","msg":"trace[1951652495] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"273.87032ms","start":"2026-07-24T17:20:59.763954Z","end":"2026-07-24T17:21:00.037824Z","steps":["trace[1951652495] 'process raft request' (duration: 273.753309ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.037834Z","caller":"traceutil/trace.go:171","msg":"trace[705443353] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"274.82084ms","start":"2026-07-24T17:20:59.762971Z","end":"2026-07-24T17:21:00.037792Z","steps":["trace[705443353] 'process raft request' (duration: 274.620877ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.037934Z","caller":"traceutil/trace.go:171","msg":"trace[178039460] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"273.952451ms","start":"2026-07-24T17:20:59.763955Z","end":"2026-07-24T17:21:00.037907Z","steps":["trace[178039460] 'process raft request' (duration: 273.786559ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.037899Z","caller":"traceutil/trace.go:171","msg":"trace[2134921530] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"273.575176ms","start":"2026-07-24T17:20:59.764278Z","end":"2026-07-24T17:21:00.037853Z","steps":["trace[2134921530] 'process raft request' (duration: 273.489375ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.038082Z","caller":"traceutil/trace.go:171","msg":"trace[1643937533] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"254.514129ms","start":"2026-07-24T17:20:59.783553Z","end":"2026-07-24T17:21:00.038067Z","steps":["trace[1643937533] 'process raft request' (duration: 254.308367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.850514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.038252Z","caller":"traceutil/trace.go:171","msg":"trace[1948449008] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"273.791329ms","start":"2026-07-24T17:20:59.764452Z","end":"2026-07-24T17:21:00.038243Z","steps":["trace[1948449008] 'process raft request' (duration: 273.335744ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.038253Z","caller":"traceutil/trace.go:171","msg":"trace[680039186] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"264.845832ms","start":"2026-07-24T17:20:59.773397Z","end":"2026-07-24T17:21:00.038243Z","steps":["trace[680039186] 'process raft request' (duration: 264.435078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.635856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.038365Z","caller":"traceutil/trace.go:171","msg":"trace[955812108] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"273.663487ms","start":"2026-07-24T17:20:59.764695Z","end":"2026-07-24T17:21:00.038358Z","steps":["trace[955812108] 'process raft request' (duration: 273.114682ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.03839Z","caller":"traceutil/trace.go:171","msg":"trace[1530586758] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"225.731978ms","start":"2026-07-24T17:20:59.812647Z","end":"2026-07-24T17:21:00.038379Z","steps":["trace[1530586758] 'agreement among raft nodes before linearized reading' (duration: 225.629737ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:00.038219Z","caller":"traceutil/trace.go:171","msg":"trace[423461078] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"289.957775ms","start":"2026-07-24T17:20:59.748242Z","end":"2026-07-24T17:21:00.038199Z","steps":["trace[423461078] 'agreement among raft nodes before linearized reading' (duration: 289.689792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.674768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.038685Z","caller":"traceutil/trace.go:171","msg":"trace[1890225666] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"274.771609ms","start":"2026-07-24T17:20:59.763902Z","end":"2026-07-24T17:21:00.038674Z","steps":["trace[1890225666] 'agreement among raft nodes before linearized reading' (duration: 274.655478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.676655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.038753Z","caller":"traceutil/trace.go:171","msg":"trace[1350085972] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"235.725135ms","start":"2026-07-24T17:20:59.803021Z","end":"2026-07-24T17:21:00.038746Z","steps":["trace[1350085972] 'agreement among raft nodes before linearized reading' (duration: 235.676075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.498869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.038785Z","caller":"traceutil/trace.go:171","msg":"trace[530208562] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:11; }","duration":"278.56778ms","start":"2026-07-24T17:20:59.760206Z","end":"2026-07-24T17:21:00.038774Z","steps":["trace[530208562] 'agreement among raft nodes before linearized reading' (duration: 278.501959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:00.038885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.989612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T17:21:00.03896Z","caller":"traceutil/trace.go:171","msg":"trace[530185143] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"276.275745ms","start":"2026-07-24T17:20:59.76267Z","end":"2026-07-24T17:21:00.038946Z","steps":["trace[530185143] 'agreement among raft nodes before linearized reading' (duration: 275.995142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:34.587934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.193929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:21:34.588033Z","caller":"traceutil/trace.go:171","msg":"trace[1582959774] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"118.419363ms","start":"2026-07-24T17:21:34.469599Z","end":"2026-07-24T17:21:34.588018Z","steps":["trace[1582959774] 'read index received' (duration: 7.017014ms)","trace[1582959774] 'applied index is now lower than readState.Index' (duration: 111.401319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:21:34.588111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.510307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:21:34.588134Z","caller":"traceutil/trace.go:171","msg":"trace[1013675765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"118.56698ms","start":"2026-07-24T17:21:34.469559Z","end":"2026-07-24T17:21:34.588126Z","steps":["trace[1013675765] 'agreement among raft nodes before linearized reading' (duration: 118.500107ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:34.588354Z","caller":"traceutil/trace.go:171","msg":"trace[1096043243] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"169.114931ms","start":"2026-07-24T17:21:34.419204Z","end":"2026-07-24T17:21:34.588319Z","steps":["trace[1096043243] 'process raft request' (duration: 57.45833ms)","trace[1096043243] 'compare' (duration: 111.084715ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:21:34.671327Z","caller":"traceutil/trace.go:171","msg":"trace[1546445311] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"184.214751ms","start":"2026-07-24T17:21:34.487098Z","end":"2026-07-24T17:21:34.671313Z","steps":["trace[1546445311] 'process raft request' (duration: 183.853654ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:34.839817Z","caller":"traceutil/trace.go:171","msg":"trace[1435640321] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"218.027587ms","start":"2026-07-24T17:21:34.621754Z","end":"2026-07-24T17:21:34.839781Z","steps":["trace[1435640321] 'process raft request' (duration: 210.31683ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:34.840614Z","caller":"traceutil/trace.go:171","msg":"trace[1422989988] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"173.256383ms","start":"2026-07-24T17:21:34.667337Z","end":"2026-07-24T17:21:34.840594Z","steps":["trace[1422989988] 'process raft request' (duration: 172.582842ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:34.840678Z","caller":"traceutil/trace.go:171","msg":"trace[725566708] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"157.737154ms","start":"2026-07-24T17:21:34.68292Z","end":"2026-07-24T17:21:34.840658Z","steps":["trace[725566708] 'process raft request' (duration: 157.597748ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:40.287664Z","caller":"traceutil/trace.go:171","msg":"trace[380895919] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"173.8266ms","start":"2026-07-24T17:21:40.113811Z","end":"2026-07-24T17:21:40.287638Z","steps":["trace[380895919] 'read index received' (duration: 173.009794ms)","trace[380895919] 'applied index is now lower than readState.Index' (duration: 815.896µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:21:40.28783Z","caller":"traceutil/trace.go:171","msg":"trace[1708168365] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"200.142552ms","start":"2026-07-24T17:21:40.087565Z","end":"2026-07-24T17:21:40.287708Z","steps":["trace[1708168365] 'process raft request' (duration: 199.512134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:40.287977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.949017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T17:21:40.288022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.118968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kh4bj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T17:21:40.288073Z","caller":"traceutil/trace.go:171","msg":"trace[1985854281] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kh4bj; range_end:; response_count:1; response_revision:630; }","duration":"134.205701ms","start":"2026-07-24T17:21:40.153851Z","end":"2026-07-24T17:21:40.288057Z","steps":["trace[1985854281] 'agreement among raft nodes before linearized reading' (duration: 134.076295ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:40.288074Z","caller":"traceutil/trace.go:171","msg":"trace[1815110893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"136.212218ms","start":"2026-07-24T17:21:40.151837Z","end":"2026-07-24T17:21:40.288049Z","steps":["trace[1815110893] 'agreement among raft nodes before linearized reading' (duration: 135.967328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:40.287894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.064159ms","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-07-24T17:21:40.288321Z","caller":"traceutil/trace.go:171","msg":"trace[1191758252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"174.550441ms","start":"2026-07-24T17:21:40.113757Z","end":"2026-07-24T17:21:40.288307Z","steps":["trace[1191758252] 'agreement among raft nodes before linearized reading' (duration: 174.009027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:40.806742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.809623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:21:40.807146Z","caller":"traceutil/trace.go:171","msg":"trace[145783102] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"462.534102ms","start":"2026-07-24T17:21:40.344577Z","end":"2026-07-24T17:21:40.807111Z","steps":["trace[145783102] 'process raft request' (duration: 269.260369ms)","trace[145783102] 'compare' (duration: 192.488419ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:21:40.807417Z","caller":"traceutil/trace.go:171","msg":"trace[365464401] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"455.282046ms","start":"2026-07-24T17:21:40.351985Z","end":"2026-07-24T17:21:40.807267Z","steps":["trace[365464401] 'process raft request' (duration: 454.883309ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:40.807711Z","caller":"traceutil/trace.go:171","msg":"trace[1113679079] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"106.319731ms","start":"2026-07-24T17:21:40.701109Z","end":"2026-07-24T17:21:40.807428Z","steps":["trace[1113679079] 'process raft request' (duration: 106.175175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:40.807913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:21:40.34456Z","time spent":"462.659686ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:21:40.80778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:21:40.351957Z","time spent":"455.58815ms","remote":"127.0.0.1:49232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:21:54.720201Z","caller":"traceutil/trace.go:171","msg":"trace[708442320] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"246.504358ms","start":"2026-07-24T17:21:54.473673Z","end":"2026-07-24T17:21:54.720178Z","steps":["trace[708442320] 'read index received' (duration: 246.274059ms)","trace[708442320] 'applied index is now lower than readState.Index' (duration: 229.369µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:21:54.720328Z","caller":"traceutil/trace.go:171","msg":"trace[254718804] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"247.361741ms","start":"2026-07-24T17:21:54.472938Z","end":"2026-07-24T17:21:54.7203Z","steps":["trace[254718804] 'process raft request' (duration: 247.081839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:21:54.720494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.525665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:21:54.720529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.84884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:21:54.720572Z","caller":"traceutil/trace.go:171","msg":"trace[164182086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"115.653259ms","start":"2026-07-24T17:21:54.604899Z","end":"2026-07-24T17:21:54.720553Z","steps":["trace[164182086] 'agreement among raft nodes before linearized reading' (duration: 115.475582ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:21:54.720603Z","caller":"traceutil/trace.go:171","msg":"trace[560986128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"246.988887ms","start":"2026-07-24T17:21:54.473602Z","end":"2026-07-24T17:21:54.720591Z","steps":["trace[560986128] 'agreement among raft nodes before linearized reading' (duration: 246.713446ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:22:27.234556Z","caller":"traceutil/trace.go:171","msg":"trace[38173770] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"207.96349ms","start":"2026-07-24T17:22:27.026567Z","end":"2026-07-24T17:22:27.23453Z","steps":["trace[38173770] 'read index received' (duration: 207.696493ms)","trace[38173770] 'applied index is now lower than readState.Index' (duration: 265.827µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:22:27.23468Z","caller":"traceutil/trace.go:171","msg":"trace[743904260] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"216.112647ms","start":"2026-07-24T17:22:27.018527Z","end":"2026-07-24T17:22:27.23464Z","steps":["trace[743904260] 'process raft request' (duration: 215.787258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:22:27.235136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.558376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T17:22:27.235197Z","caller":"traceutil/trace.go:171","msg":"trace[1571014168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"208.680019ms","start":"2026-07-24T17:22:27.026502Z","end":"2026-07-24T17:22:27.235182Z","steps":["trace[1571014168] 'agreement among raft nodes before linearized reading' (duration: 208.140344ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:23:19.415158Z","caller":"traceutil/trace.go:171","msg":"trace[1858482047] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"205.620742ms","start":"2026-07-24T17:23:19.209483Z","end":"2026-07-24T17:23:19.415103Z","steps":["trace[1858482047] 'process raft request' (duration: 205.501069ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:23:19.523342Z","caller":"traceutil/trace.go:171","msg":"trace[225649568] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"312.299502ms","start":"2026-07-24T17:23:19.211014Z","end":"2026-07-24T17:23:19.523314Z","steps":["trace[225649568] 'process raft request' (duration: 299.623549ms)","trace[225649568] 'compare' (duration: 12.50049ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:23:19.523576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:23:19.210992Z","time spent":"312.416495ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:23:19.916869Z","caller":"traceutil/trace.go:171","msg":"trace[1257921196] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"103.482818ms","start":"2026-07-24T17:23:19.81336Z","end":"2026-07-24T17:23:19.916843Z","steps":["trace[1257921196] 'read index received' (duration: 103.236413ms)","trace[1257921196] 'applied index is now lower than readState.Index' (duration: 245.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:23:19.916973Z","caller":"traceutil/trace.go:171","msg":"trace[368673958] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"250.452477ms","start":"2026-07-24T17:23:19.666487Z","end":"2026-07-24T17:23:19.916939Z","steps":["trace[368673958] 'process raft request' (duration: 250.190182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:23:19.91712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.746844ms","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-07-24T17:23:19.917163Z","caller":"traceutil/trace.go:171","msg":"trace[458097883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"103.842225ms","start":"2026-07-24T17:23:19.81331Z","end":"2026-07-24T17:23:19.917152Z","steps":["trace[458097883] 'agreement among raft nodes before linearized reading' (duration: 103.635851ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:23:34.84424Z","caller":"traceutil/trace.go:171","msg":"trace[1348629291] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"140.696867ms","start":"2026-07-24T17:23:34.703513Z","end":"2026-07-24T17:23:34.84421Z","steps":["trace[1348629291] 'process raft request' (duration: 140.533594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:24:08.752355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.129099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:24:08.752725Z","caller":"traceutil/trace.go:171","msg":"trace[1781762057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2211; }","duration":"147.580147ms","start":"2026-07-24T17:24:08.605119Z","end":"2026-07-24T17:24:08.752699Z","steps":["trace[1781762057] 'range keys from in-memory index tree' (duration: 147.047198ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:24:08.859134Z","caller":"traceutil/trace.go:171","msg":"trace[119072207] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"102.281002ms","start":"2026-07-24T17:24:08.756819Z","end":"2026-07-24T17:24:08.8591Z","steps":["trace[119072207] 'process raft request' (duration: 102.119679ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:24:58.781067Z","caller":"traceutil/trace.go:171","msg":"trace[765892477] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"106.233041ms","start":"2026-07-24T17:24:58.674808Z","end":"2026-07-24T17:24:58.781041Z","steps":["trace[765892477] 'process raft request' (duration: 105.799594ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:25:33.470035Z","caller":"traceutil/trace.go:171","msg":"trace[1672526713] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"148.519036ms","start":"2026-07-24T17:25:33.321474Z","end":"2026-07-24T17:25:33.469993Z","steps":["trace[1672526713] 'process raft request' (duration: 128.11214ms)","trace[1672526713] 'compare' (duration: 20.300255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:29:11.473909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.1536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T17:29:11.474042Z","caller":"traceutil/trace.go:171","msg":"trace[588261331] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:4643; }","duration":"100.334944ms","start":"2026-07-24T17:29:11.373688Z","end":"2026-07-24T17:29:11.474023Z","steps":["trace[588261331] 'range keys from in-memory index tree' (duration: 99.997067ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:19.347389Z","caller":"traceutil/trace.go:171","msg":"trace[270279515] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"103.123504ms","start":"2026-07-24T17:29:19.244243Z","end":"2026-07-24T17:29:19.347367Z","steps":["trace[270279515] 'process raft request' (duration: 39.240202ms)","trace[270279515] 'compare' (duration: 63.641987ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:19.509021Z","caller":"traceutil/trace.go:171","msg":"trace[1171435318] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"176.342992ms","start":"2026-07-24T17:29:19.332648Z","end":"2026-07-24T17:29:19.508991Z","steps":["trace[1171435318] 'process raft request' (duration: 158.046441ms)","trace[1171435318] 'compare' (duration: 18.143438ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:19.543556Z","caller":"traceutil/trace.go:171","msg":"trace[980382398] linearizableReadLoop","detail":"{readStateIndex:4972; appliedIndex:4970; }","duration":"135.162988ms","start":"2026-07-24T17:29:19.408175Z","end":"2026-07-24T17:29:19.543338Z","steps":["trace[980382398] 'read index received' (duration: 82.530132ms)","trace[980382398] 'applied index is now lower than readState.Index' (duration: 52.631886ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:19.543685Z","caller":"traceutil/trace.go:171","msg":"trace[909618652] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"186.084127ms","start":"2026-07-24T17:29:19.357539Z","end":"2026-07-24T17:29:19.543623Z","steps":["trace[909618652] 'process raft request' (duration: 185.629427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:19.543801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.608889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-124de2b1-8ce5-420a-9ece-2e150c9fb113\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-24T17:29:19.543873Z","caller":"traceutil/trace.go:171","msg":"trace[513656081] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-124de2b1-8ce5-420a-9ece-2e150c9fb113; range_end:; response_count:1; response_revision:4796; }","duration":"135.739271ms","start":"2026-07-24T17:29:19.408122Z","end":"2026-07-24T17:29:19.543861Z","steps":["trace[513656081] 'agreement among raft nodes before linearized reading' (duration: 135.450995ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:19.680689Z","caller":"traceutil/trace.go:171","msg":"trace[994844452] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"126.024318ms","start":"2026-07-24T17:29:19.554636Z","end":"2026-07-24T17:29:19.68066Z","steps":["trace[994844452] 'process raft request' (duration: 85.917236ms)","trace[994844452] 'compare' (duration: 39.406556ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:19.6808Z","caller":"traceutil/trace.go:171","msg":"trace[20333639] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"123.805048ms","start":"2026-07-24T17:29:19.556957Z","end":"2026-07-24T17:29:19.680762Z","steps":["trace[20333639] 'process raft request' (duration: 123.167843ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:20.088943Z","caller":"traceutil/trace.go:171","msg":"trace[1868493223] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"318.728365ms","start":"2026-07-24T17:29:19.770191Z","end":"2026-07-24T17:29:20.088919Z","steps":["trace[1868493223] 'read index received' (duration: 296.366454ms)","trace[1868493223] 'applied index is now lower than readState.Index' (duration: 22.360341ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:20.089287Z","caller":"traceutil/trace.go:171","msg":"trace[1529366806] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"319.851711ms","start":"2026-07-24T17:29:19.769404Z","end":"2026-07-24T17:29:20.089256Z","steps":["trace[1529366806] 'process raft request' (duration: 297.129392ms)","trace[1529366806] 'compare' (duration: 21.90395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:29:20.089736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:19.769374Z","time spent":"320.29089ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:29:20.091311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.086927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9696"} {"level":"info","ts":"2026-07-24T17:29:20.091393Z","caller":"traceutil/trace.go:171","msg":"trace[1835848608] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4801; }","duration":"321.209929ms","start":"2026-07-24T17:29:19.770164Z","end":"2026-07-24T17:29:20.091374Z","steps":["trace[1835848608] 'agreement among raft nodes before linearized reading' (duration: 319.126234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:20.091464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:19.770144Z","time spent":"321.309021ms","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9718,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-24T17:29:20.123575Z","caller":"traceutil/trace.go:171","msg":"trace[1033240892] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"330.8653ms","start":"2026-07-24T17:29:19.79268Z","end":"2026-07-24T17:29:20.123545Z","steps":["trace[1033240892] 'process raft request' (duration: 330.677836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:20.12396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:19.79265Z","time spent":"331.017354ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:29:23.25795Z","caller":"traceutil/trace.go:171","msg":"trace[2127783696] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"112.992865ms","start":"2026-07-24T17:29:23.144936Z","end":"2026-07-24T17:29:23.257929Z","steps":["trace[2127783696] 'process raft request' (duration: 112.645958ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:32.348359Z","caller":"traceutil/trace.go:171","msg":"trace[291934259] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"260.857457ms","start":"2026-07-24T17:29:32.087479Z","end":"2026-07-24T17:29:32.348336Z","steps":["trace[291934259] 'process raft request' (duration: 260.681024ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:32.348496Z","caller":"traceutil/trace.go:171","msg":"trace[2111227284] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5137; }","duration":"147.419899ms","start":"2026-07-24T17:29:32.201057Z","end":"2026-07-24T17:29:32.348477Z","steps":["trace[2111227284] 'read index received' (duration: 147.411129ms)","trace[2111227284] 'applied index is now lower than readState.Index' (duration: 7.11µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:29:32.348683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.603223ms","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-07-24T17:29:32.348747Z","caller":"traceutil/trace.go:171","msg":"trace[991634470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4954; }","duration":"147.716646ms","start":"2026-07-24T17:29:32.201014Z","end":"2026-07-24T17:29:32.348731Z","steps":["trace[991634470] 'agreement among raft nodes before linearized reading' (duration: 147.546102ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:32.35659Z","caller":"traceutil/trace.go:171","msg":"trace[1113120680] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"154.042771ms","start":"2026-07-24T17:29:32.202525Z","end":"2026-07-24T17:29:32.356568Z","steps":["trace[1113120680] 'process raft request' (duration: 153.922089ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:30:58.429501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-07-24T17:30:58.513228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"82.110333ms","hash":984124329,"current-db-size-bytes":54710272,"current-db-size":"55 MB","current-db-size-in-use-bytes":29818880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T17:30:58.5133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":984124329,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-07-24T17:35:58.434631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5690} {"level":"info","ts":"2026-07-24T17:35:58.507879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5690,"took":"72.050131ms","hash":1344276169,"current-db-size-bytes":54710272,"current-db-size":"55 MB","current-db-size-in-use-bytes":18616320,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-24T17:35:58.507957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1344276169,"revision":5690,"compact-revision":2911}