{"level":"warn","ts":"2026-08-12T00:25:51.614195Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:25:51.614463Z","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-08-12T00:25:51.614627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:25:51.614668Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-12T00:25:51.614751Z","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-08-12T00:25:51.615684Z","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-08-12T00:25:51.615875Z","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.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-08-12T00:25:51.623691Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.447336ms"} {"level":"info","ts":"2026-08-12T00:25:51.63893Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-12T00:25:51.639155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T00:25:51.639291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-12T00:25:51.639348Z","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-08-12T00:25:51.639464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-12T00:25:51.639576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-12T00:25:51.666572Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T00:25:51.672115Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T00:25:51.685011Z","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-08-12T00:25:51.693097Z","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-08-12T00:25:51.69323Z","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-08-12T00:25:51.693352Z","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-08-12T00:25:51.693367Z","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-08-12T00:25:51.694461Z","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-08-12T00:25:51.697906Z","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-08-12T00:25:51.698034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-12T00:25:51.698136Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-12T00:25:51.698164Z","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-08-12T00:25:51.698211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T00:25:51.710859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-12T00:25:51.711042Z","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-08-12T00:25:52.241279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T00:25:52.241619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T00:25:52.241742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-12T00:25:52.24187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-12T00:25:52.241975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-12T00:25:52.24211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-12T00:25:52.242236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-12T00:25:52.246148Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:52.250116Z","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-08-12T00:25:52.250129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:25:52.250161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:25:52.250486Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T00:25:52.250571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T00:25:52.254526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-12T00:25:52.25524Z","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-08-12T00:25:52.255456Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:52.255506Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:25:52.255817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-12T00:25:54.455111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.958971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-12T00:25:54.455289Z","caller":"traceutil/trace.go:171","msg":"trace[916416461] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"796.591141ms","start":"2026-08-12T00:25:53.658676Z","end":"2026-08-12T00:25:54.455267Z","steps":["trace[916416461] 'read index received' (duration: 483.805055ms)","trace[916416461] 'applied index is now lower than readState.Index' (duration: 312.784596ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:25:54.455539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.219048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:54.455581Z","caller":"traceutil/trace.go:171","msg":"trace[107607128] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:3; }","duration":"841.27478ms","start":"2026-08-12T00:25:53.614292Z","end":"2026-08-12T00:25:54.455567Z","steps":["trace[107607128] 'agreement among raft nodes before linearized reading' (duration: 841.080282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:54.45557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.111163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:54.455628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.614285Z","time spent":"841.331853ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-08-12T00:25:54.455677Z","caller":"traceutil/trace.go:171","msg":"trace[234454262] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:3; }","duration":"841.247909ms","start":"2026-08-12T00:25:53.614406Z","end":"2026-08-12T00:25:54.455654Z","steps":["trace[234454262] 'agreement among raft nodes before linearized reading' (duration: 840.900815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:54.455765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.614401Z","time spent":"841.354214ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-08-12T00:25:54.455743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.281789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:54.455723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.833554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:54.455915Z","caller":"traceutil/trace.go:171","msg":"trace[1265505337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3; }","duration":"836.519219ms","start":"2026-08-12T00:25:53.619371Z","end":"2026-08-12T00:25:54.455891Z","steps":["trace[1265505337] 'agreement among raft nodes before linearized reading' (duration: 835.960046ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:54.455977Z","caller":"traceutil/trace.go:171","msg":"trace[458816024] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"829.480942ms","start":"2026-08-12T00:25:53.625804Z","end":"2026-08-12T00:25:54.455285Z","steps":["trace[458816024] 'process raft request' (duration: 516.654764ms)","trace[458816024] 'compare' (duration: 311.736202ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:25:54.456252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.625794Z","time spent":"830.378189ms","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:54.456044Z","caller":"traceutil/trace.go:171","msg":"trace[111570147] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:3; }","duration":"842.259532ms","start":"2026-08-12T00:25:53.613742Z","end":"2026-08-12T00:25:54.456001Z","steps":["trace[111570147] 'agreement among raft nodes before linearized reading' (duration: 841.667667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:54.456471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.613737Z","time spent":"842.716891ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-08-12T00:25:54.456909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.619365Z","time spent":"836.653815ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:25:54.955888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534361168222074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T00:25:55.374331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.817213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-12T00:25:55.374711Z","caller":"traceutil/trace.go:171","msg":"trace[1486913088] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.672214088s","start":"2026-08-12T00:25:53.702471Z","end":"2026-08-12T00:25:55.374685Z","steps":["trace[1486913088] 'process raft request' (duration: 1.320930236s)","trace[1486913088] 'compare' (duration: 350.595113ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:25:55.374822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.702451Z","time spent":"1.672312733s","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:55.374921Z","caller":"traceutil/trace.go:171","msg":"trace[456132418] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.667379564s","start":"2026-08-12T00:25:53.707524Z","end":"2026-08-12T00:25:55.374904Z","steps":["trace[456132418] 'process raft request' (duration: 1.667042799s)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.374912Z","caller":"traceutil/trace.go:171","msg":"trace[2131204408] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.667117443s","start":"2026-08-12T00:25:53.707743Z","end":"2026-08-12T00:25:55.374861Z","steps":["trace[2131204408] 'process raft request' (duration: 1.666968076s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.375003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.707515Z","time spent":"1.667444166s","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:25:55.375062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.707705Z","time spent":"1.667284039s","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:55.375075Z","caller":"traceutil/trace.go:171","msg":"trace[1921370537] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.667142243s","start":"2026-08-12T00:25:53.70787Z","end":"2026-08-12T00:25:55.375012Z","steps":["trace[1921370537] 'process raft request' (duration: 1.666876392s)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.375348Z","caller":"traceutil/trace.go:171","msg":"trace[1304862388] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.667646224s","start":"2026-08-12T00:25:53.707676Z","end":"2026-08-12T00:25:55.375323Z","steps":["trace[1304862388] 'process raft request' (duration: 1.667000687s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.376833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.707671Z","time spent":"1.669101896s","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:25:55.377639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.707861Z","time spent":"1.66729075s","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:55.377291Z","caller":"traceutil/trace.go:171","msg":"trace[1796647146] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.666698994s","start":"2026-08-12T00:25:53.70846Z","end":"2026-08-12T00:25:55.375159Z","steps":["trace[1796647146] 'process raft request' (duration: 1.666323738s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.379321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.708435Z","time spent":"1.670544057s","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:55.383008Z","caller":"traceutil/trace.go:171","msg":"trace[102915065] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.669761664s","start":"2026-08-12T00:25:53.713222Z","end":"2026-08-12T00:25:55.382984Z","steps":["trace[102915065] 'process raft request' (duration: 1.661608249s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.383099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.71321Z","time spent":"1.669850398s","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T00:25:55.597019Z","caller":"traceutil/trace.go:171","msg":"trace[184526329] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"1.141618031s","start":"2026-08-12T00:25:54.455336Z","end":"2026-08-12T00:25:55.596954Z","steps":["trace[184526329] 'read index received' (duration: 567.861395ms)","trace[184526329] 'applied index is now lower than readState.Index' (duration: 573.755506ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:25:55.597082Z","caller":"traceutil/trace.go:171","msg":"trace[1414855597] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.139794604s","start":"2026-08-12T00:25:54.457242Z","end":"2026-08-12T00:25:55.597036Z","steps":["trace[1414855597] 'process raft request' (duration: 1.09303969s)","trace[1414855597] 'compare' (duration: 46.532914ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:25:55.597267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.897336664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:55.597247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895261486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.597319Z","caller":"traceutil/trace.go:171","msg":"trace[916271866] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:0; response_revision:11; }","duration":"1.897468399s","start":"2026-08-12T00:25:53.699837Z","end":"2026-08-12T00:25:55.597305Z","steps":["trace[916271866] 'agreement among raft nodes before linearized reading' (duration: 1.897283341s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.597271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841609877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:55.597439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.935879524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:55.597512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895149581s","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-08-12T00:25:55.597458Z","caller":"traceutil/trace.go:171","msg":"trace[1311432893] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.841860758s","start":"2026-08-12T00:25:53.75558Z","end":"2026-08-12T00:25:55.597441Z","steps":["trace[1311432893] 'agreement among raft nodes before linearized reading' (duration: 1.841621938s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.597759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.755558Z","time spent":"1.842185402s","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-12T00:25:55.597821Z","caller":"traceutil/trace.go:171","msg":"trace[919497112] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.895306208s","start":"2026-08-12T00:25:53.702306Z","end":"2026-08-12T00:25:55.597612Z","steps":["trace[919497112] 'agreement among raft nodes before linearized reading' (duration: 1.894670051s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.59739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.699827Z","time spent":"1.897550693s","remote":"127.0.0.1:32790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-08-12T00:25:55.597971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.702277Z","time spent":"1.89558202s","remote":"127.0.0.1:32848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-08-12T00:25:55.598188Z","caller":"traceutil/trace.go:171","msg":"trace[483376395] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"213.454292ms","start":"2026-08-12T00:25:55.384706Z","end":"2026-08-12T00:25:55.59816Z","steps":["trace[483376395] 'process raft request' (duration: 213.214132ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.597524Z","caller":"traceutil/trace.go:171","msg":"trace[1265996093] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.935966717s","start":"2026-08-12T00:25:53.661534Z","end":"2026-08-12T00:25:55.597501Z","steps":["trace[1265996093] 'agreement among raft nodes before linearized reading' (duration: 1.935841402s)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.598288Z","caller":"traceutil/trace.go:171","msg":"trace[1903860774] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"218.077028ms","start":"2026-08-12T00:25:55.38016Z","end":"2026-08-12T00:25:55.598237Z","steps":["trace[1903860774] 'process raft request' (duration: 217.520704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.598317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.661466Z","time spent":"1.936836414s","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-08-12T00:25:55.598546Z","caller":"traceutil/trace.go:171","msg":"trace[111202200] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"213.806728ms","start":"2026-08-12T00:25:55.384722Z","end":"2026-08-12T00:25:55.598528Z","steps":["trace[111202200] 'process raft request' (duration: 213.368079ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.598513Z","caller":"traceutil/trace.go:171","msg":"trace[793154090] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"213.748125ms","start":"2026-08-12T00:25:55.384706Z","end":"2026-08-12T00:25:55.598454Z","steps":["trace[793154090] 'process raft request' (duration: 213.241963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.597528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.457214Z","time spent":"1.13992913s","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:25:55.598842Z","caller":"traceutil/trace.go:171","msg":"trace[1981559786] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"214.048518ms","start":"2026-08-12T00:25:55.384779Z","end":"2026-08-12T00:25:55.598828Z","steps":["trace[1981559786] 'process raft request' (duration: 213.517605ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.597354Z","caller":"traceutil/trace.go:171","msg":"trace[605134709] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.895432043s","start":"2026-08-12T00:25:53.701898Z","end":"2026-08-12T00:25:55.59733Z","steps":["trace[605134709] 'agreement among raft nodes before linearized reading' (duration: 1.895169172s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.599012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:53.701888Z","time spent":"1.897103643s","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-12T00:25:55.600273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141072208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-08-12T00:25:55.60034Z","caller":"traceutil/trace.go:171","msg":"trace[1150030968] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"1.141214975s","start":"2026-08-12T00:25:54.459108Z","end":"2026-08-12T00:25:55.600323Z","steps":["trace[1150030968] 'agreement among raft nodes before linearized reading' (duration: 1.140974715s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.600377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.459085Z","time spent":"1.141282157s","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-12T00:25:55.600499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.245595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.600572Z","caller":"traceutil/trace.go:171","msg":"trace[1850227234] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"999.370871ms","start":"2026-08-12T00:25:54.601183Z","end":"2026-08-12T00:25:55.600554Z","steps":["trace[1850227234] 'agreement among raft nodes before linearized reading' (duration: 999.255156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.600592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.58574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:55.60062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.601154Z","time spent":"999.452724ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-08-12T00:25:55.600682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088783279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.600712Z","caller":"traceutil/trace.go:171","msg":"trace[12071611] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.088831461s","start":"2026-08-12T00:25:54.511871Z","end":"2026-08-12T00:25:55.600703Z","steps":["trace[12071611] 'agreement among raft nodes before linearized reading' (duration: 1.088776349s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.600754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.511861Z","time spent":"1.088887234s","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-12T00:25:55.600756Z","caller":"traceutil/trace.go:171","msg":"trace[2105069729] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"211.325713ms","start":"2026-08-12T00:25:55.389412Z","end":"2026-08-12T00:25:55.600737Z","steps":["trace[2105069729] 'process raft request' (duration: 208.923692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.600897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.780777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T00:25:55.600973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139852237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-bnd48\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-12T00:25:55.598296Z","caller":"traceutil/trace.go:171","msg":"trace[891817284] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"213.680782ms","start":"2026-08-12T00:25:55.384547Z","end":"2026-08-12T00:25:55.598228Z","steps":["trace[891817284] 'process raft request' (duration: 213.235393ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.601017Z","caller":"traceutil/trace.go:171","msg":"trace[1106628741] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-bnd48; range_end:; response_count:1; response_revision:18; }","duration":"1.139941421s","start":"2026-08-12T00:25:54.461065Z","end":"2026-08-12T00:25:55.601006Z","steps":["trace[1106628741] 'agreement among raft nodes before linearized reading' (duration: 1.139851167s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.601056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.46105Z","time spent":"1.139998264s","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-bnd48\" "} {"level":"info","ts":"2026-08-12T00:25:55.600975Z","caller":"traceutil/trace.go:171","msg":"trace[1624650982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"892.883281ms","start":"2026-08-12T00:25:54.708075Z","end":"2026-08-12T00:25:55.600958Z","steps":["trace[1624650982] 'agreement among raft nodes before linearized reading' (duration: 892.781336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.601208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.708062Z","time spent":"893.133052ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T00:25:55.600628Z","caller":"traceutil/trace.go:171","msg":"trace[405878781] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"999.640102ms","start":"2026-08-12T00:25:54.600976Z","end":"2026-08-12T00:25:55.600616Z","steps":["trace[405878781] 'agreement among raft nodes before linearized reading' (duration: 999.5845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.601316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:25:54.600965Z","time spent":"1.000341521s","remote":"127.0.0.1:33130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-08-12T00:25:55.7535Z","caller":"traceutil/trace.go:171","msg":"trace[1572059609] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:33; }","duration":"134.932248ms","start":"2026-08-12T00:25:55.618548Z","end":"2026-08-12T00:25:55.75348Z","steps":["trace[1572059609] 'read index received' (duration: 63.103685ms)","trace[1572059609] 'applied index is now lower than readState.Index' (duration: 71.827863ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:25:55.75373Z","caller":"traceutil/trace.go:171","msg":"trace[1331821388] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"134.018509ms","start":"2026-08-12T00:25:55.619702Z","end":"2026-08-12T00:25:55.75372Z","steps":["trace[1331821388] 'process raft request' (duration: 133.709766ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.753771Z","caller":"traceutil/trace.go:171","msg":"trace[786166182] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"135.571215ms","start":"2026-08-12T00:25:55.618187Z","end":"2026-08-12T00:25:55.753758Z","steps":["trace[786166182] 'process raft request' (duration: 126.217459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.754101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.532923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.754148Z","caller":"traceutil/trace.go:171","msg":"trace[1613050220] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:36; }","duration":"135.603026ms","start":"2026-08-12T00:25:55.618531Z","end":"2026-08-12T00:25:55.754134Z","steps":["trace[1613050220] 'agreement among raft nodes before linearized reading' (duration: 135.471351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.75424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.570795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.754263Z","caller":"traceutil/trace.go:171","msg":"trace[2137177104] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:36; }","duration":"135.611797ms","start":"2026-08-12T00:25:55.618645Z","end":"2026-08-12T00:25:55.754257Z","steps":["trace[2137177104] 'agreement among raft nodes before linearized reading' (duration: 135.574905ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.754256Z","caller":"traceutil/trace.go:171","msg":"trace[910897944] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"135.812754ms","start":"2026-08-12T00:25:55.61837Z","end":"2026-08-12T00:25:55.754183Z","steps":["trace[910897944] 'process raft request' (duration: 134.966929ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.754202Z","caller":"traceutil/trace.go:171","msg":"trace[1813469102] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"135.893698ms","start":"2026-08-12T00:25:55.618279Z","end":"2026-08-12T00:25:55.754173Z","steps":["trace[1813469102] 'process raft request' (duration: 134.979969ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.753652Z","caller":"traceutil/trace.go:171","msg":"trace[1745256470] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"135.22012ms","start":"2026-08-12T00:25:55.618395Z","end":"2026-08-12T00:25:55.753615Z","steps":["trace[1745256470] 'process raft request' (duration: 134.98294ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:25:55.754413Z","caller":"traceutil/trace.go:171","msg":"trace[1487353231] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"136.077416ms","start":"2026-08-12T00:25:55.618327Z","end":"2026-08-12T00:25:55.754405Z","steps":["trace[1487353231] 'process raft request' (duration: 134.99283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:55.754477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.761053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T00:25:55.754548Z","caller":"traceutil/trace.go:171","msg":"trace[1802209103] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:36; }","duration":"135.821975ms","start":"2026-08-12T00:25:55.618678Z","end":"2026-08-12T00:25:55.7545Z","steps":["trace[1802209103] 'agreement among raft nodes before linearized reading' (duration: 135.775103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:25:56.616683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.935581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-12T00:25:56.616912Z","caller":"traceutil/trace.go:171","msg":"trace[2034980393] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"150.307942ms","start":"2026-08-12T00:25:56.466576Z","end":"2026-08-12T00:25:56.616884Z","steps":["trace[2034980393] 'compare' (duration: 142.684701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:37.925402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.556049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:37.925492Z","caller":"traceutil/trace.go:171","msg":"trace[512238966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:648; }","duration":"112.724952ms","start":"2026-08-12T00:26:37.812745Z","end":"2026-08-12T00:26:37.92547Z","steps":["trace[512238966] 'range keys from in-memory index tree' (duration: 112.461279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.658195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534361168223423,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T00:26:44.692334Z","caller":"traceutil/trace.go:171","msg":"trace[2015250331] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"564.344227ms","start":"2026-08-12T00:26:44.127931Z","end":"2026-08-12T00:26:44.692275Z","steps":["trace[2015250331] 'process raft request' (duration: 536.775036ms)","trace[2015250331] 'compare' (duration: 27.342178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:44.692504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.969761ms","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-08-12T00:26:44.692541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.127875Z","time spent":"564.55719ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:26:44.692577Z","caller":"traceutil/trace.go:171","msg":"trace[1511624861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"535.089102ms","start":"2026-08-12T00:26:44.15747Z","end":"2026-08-12T00:26:44.692559Z","steps":["trace[1511624861] 'agreement among raft nodes before linearized reading' (duration: 534.850298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.692624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.157454Z","time spent":"535.159983ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T00:26:44.692292Z","caller":"traceutil/trace.go:171","msg":"trace[701520119] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"534.744288ms","start":"2026-08-12T00:26:44.157519Z","end":"2026-08-12T00:26:44.692263Z","steps":["trace[701520119] 'read index received' (duration: 507.016744ms)","trace[701520119] 'applied index is now lower than readState.Index' (duration: 27.726474ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:44.692979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.912845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:44.693005Z","caller":"traceutil/trace.go:171","msg":"trace[2093483767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"447.978886ms","start":"2026-08-12T00:26:44.245017Z","end":"2026-08-12T00:26:44.692996Z","steps":["trace[2093483767] 'agreement among raft nodes before linearized reading' (duration: 447.936095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.69303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.244997Z","time spent":"448.027736ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:26:44.693262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.739542ms","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-08-12T00:26:44.693326Z","caller":"traceutil/trace.go:171","msg":"trace[247847152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"337.837584ms","start":"2026-08-12T00:26:44.35547Z","end":"2026-08-12T00:26:44.693308Z","steps":["trace[247847152] 'agreement among raft nodes before linearized reading' (duration: 337.678812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.693364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.355445Z","time spent":"337.912125ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T00:26:44.693496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.232705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rcw22\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-12T00:26:44.69362Z","caller":"traceutil/trace.go:171","msg":"trace[1309139237] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rcw22; range_end:; response_count:1; response_revision:705; }","duration":"349.429388ms","start":"2026-08-12T00:26:44.344162Z","end":"2026-08-12T00:26:44.693591Z","steps":["trace[1309139237] 'agreement among raft nodes before linearized reading' (duration: 349.040463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:44.693684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:44.344146Z","time spent":"349.52683ms","remote":"127.0.0.1:32932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-rcw22\" "} {"level":"info","ts":"2026-08-12T00:26:44.814934Z","caller":"traceutil/trace.go:171","msg":"trace[2025517387] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"116.085867ms","start":"2026-08-12T00:26:44.698787Z","end":"2026-08-12T00:26:44.814873Z","steps":["trace[2025517387] 'process raft request' (duration: 115.364147ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:44.815361Z","caller":"traceutil/trace.go:171","msg":"trace[2020112681] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"117.964544ms","start":"2026-08-12T00:26:44.697358Z","end":"2026-08-12T00:26:44.815322Z","steps":["trace[2020112681] 'process raft request' (duration: 101.158735ms)","trace[2020112681] 'compare' (duration: 15.49118ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:44.815745Z","caller":"traceutil/trace.go:171","msg":"trace[978086915] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"108.900425ms","start":"2026-08-12T00:26:44.706823Z","end":"2026-08-12T00:26:44.815724Z","steps":["trace[978086915] 'process raft request' (duration: 107.406574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:45.70478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.647026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:45.704875Z","caller":"traceutil/trace.go:171","msg":"trace[586458033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"458.770148ms","start":"2026-08-12T00:26:45.24608Z","end":"2026-08-12T00:26:45.70485Z","steps":["trace[586458033] 'range keys from in-memory index tree' (duration: 458.500983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:45.704923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:26:45.245993Z","time spent":"458.920289ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:26:45.704666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.090581ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:45.705007Z","caller":"traceutil/trace.go:171","msg":"trace[227869508] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:709; }","duration":"481.449426ms","start":"2026-08-12T00:26:45.223532Z","end":"2026-08-12T00:26:45.704982Z","steps":["trace[227869508] 'range keys from in-memory index tree' (duration: 481.068091ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:30:35.320631Z","caller":"traceutil/trace.go:171","msg":"trace[53892008] linearizableReadLoop","detail":"{readStateIndex:1704; appliedIndex:1703; }","duration":"104.564044ms","start":"2026-08-12T00:30:35.216037Z","end":"2026-08-12T00:30:35.320601Z","steps":["trace[53892008] 'read index received' (duration: 92.117001ms)","trace[53892008] 'applied index is now lower than readState.Index' (duration: 12.445203ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:30:35.32077Z","caller":"traceutil/trace.go:171","msg":"trace[353637745] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"155.624639ms","start":"2026-08-12T00:30:35.165129Z","end":"2026-08-12T00:30:35.320754Z","steps":["trace[353637745] 'process raft request' (duration: 143.091414ms)","trace[353637745] 'compare' (duration: 12.013142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:30:35.321106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.044027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-08-12T00:30:35.321211Z","caller":"traceutil/trace.go:171","msg":"trace[1002326079] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1630; }","duration":"105.180091ms","start":"2026-08-12T00:30:35.216006Z","end":"2026-08-12T00:30:35.321186Z","steps":["trace[1002326079] 'agreement among raft nodes before linearized reading' (duration: 104.943794ms)"],"step_count":1}