{"level":"warn","ts":"2026-10-04T12:51:18.92427Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:51:18.924345Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.112: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://162.253.55.112:2380","--initial-cluster=instance=https://162.253.55.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.112: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-10-04T12:51:18.924395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:51:18.924408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-10-04T12:51:18.924427Z","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-10-04T12:51:18.925004Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"]} {"level":"info","ts":"2026-10-04T12:51:18.925068Z","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://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.112: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-10-04T12:51:18.928867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.610991ms"} {"level":"info","ts":"2026-10-04T12:51:18.937406Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1b80133015df7bbe","cluster-id":"96c35284d1622e20"} {"level":"info","ts":"2026-10-04T12:51:18.937483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T12:51:18.937504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 0"} {"level":"info","ts":"2026-10-04T12:51:18.937521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1b80133015df7bbe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T12:51:18.937528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 1"} {"level":"info","ts":"2026-10-04T12:51:18.93756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"warn","ts":"2026-10-04T12:51:18.94451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T12:51:18.946063Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T12:51:18.947362Z","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-10-04T12:51:18.949141Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1b80133015df7bbe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T12:51:18.949602Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1b80133015df7bbe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T12:51:18.949645Z","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-10-04T12:51:18.94967Z","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-10-04T12:51:18.949675Z","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-10-04T12:51:18.951124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"info","ts":"2026-10-04T12:51:18.951202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","added-peer-id":"1b80133015df7bbe","added-peer-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-10-04T12:51:18.95168Z","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-10-04T12:51:18.951711Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-10-04T12:51:18.951728Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-10-04T12:51:18.951823Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1b80133015df7bbe","initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T12:51:18.951857Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T12:51:19.338512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T12:51:19.338571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T12:51:19.338608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgPreVoteResp from 1b80133015df7bbe at term 1"} {"level":"info","ts":"2026-10-04T12:51:19.338631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became candidate at term 2"} {"level":"info","ts":"2026-10-04T12:51:19.338646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgVoteResp from 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-10-04T12:51:19.338665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became leader at term 2"} {"level":"info","ts":"2026-10-04T12:51:19.338678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1b80133015df7bbe elected leader 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-10-04T12:51:19.340419Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1b80133015df7bbe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.112:2379]}","request-path":"/0/members/1b80133015df7bbe/attributes","cluster-id":"96c35284d1622e20","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T12:51:19.340506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:51:19.340638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:51:19.341061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T12:51:19.341086Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T12:51:19.341121Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:51:19.343907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T12:51:19.344314Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:51:19.344135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.112:2379"} {"level":"info","ts":"2026-10-04T12:51:19.344493Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:51:19.344635Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:51:21.216533Z","caller":"traceutil/trace.go:171","msg":"trace[381221038] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"202.845733ms","start":"2026-10-04T12:51:21.013665Z","end":"2026-10-04T12:51:21.216511Z","steps":["trace[381221038] 'process raft request' (duration: 202.618288ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.530658Z","caller":"traceutil/trace.go:171","msg":"trace[863245544] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"515.984253ms","start":"2026-10-04T12:51:21.01464Z","end":"2026-10-04T12:51:21.530625Z","steps":["trace[863245544] 'process raft request' (duration: 515.669356ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.530933Z","caller":"traceutil/trace.go:171","msg":"trace[1004525163] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"504.354792ms","start":"2026-10-04T12:51:21.026561Z","end":"2026-10-04T12:51:21.530916Z","steps":["trace[1004525163] 'read index received' (duration: 189.82702ms)","trace[1004525163] 'applied index is now lower than readState.Index' (duration: 314.526942ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:21.531181Z","caller":"traceutil/trace.go:171","msg":"trace[423155850] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"458.459982ms","start":"2026-10-04T12:51:21.072708Z","end":"2026-10-04T12:51:21.531168Z","steps":["trace[423155850] 'process raft request' (duration: 457.849058ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.531235Z","caller":"traceutil/trace.go:171","msg":"trace[1517949321] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"417.796469ms","start":"2026-10-04T12:51:21.113374Z","end":"2026-10-04T12:51:21.531171Z","steps":["trace[1517949321] 'process raft request' (duration: 417.496053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.66599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.531389Z","caller":"traceutil/trace.go:171","msg":"trace[612198750] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"464.776953ms","start":"2026-10-04T12:51:21.066603Z","end":"2026-10-04T12:51:21.53138Z","steps":["trace[612198750] 'agreement among raft nodes before linearized reading' (duration: 464.62211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.066581Z","time spent":"464.824215ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-04T12:51:21.531498Z","caller":"traceutil/trace.go:171","msg":"trace[599398503] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"458.527324ms","start":"2026-10-04T12:51:21.072841Z","end":"2026-10-04T12:51:21.531368Z","steps":["trace[599398503] 'process raft request' (duration: 457.879209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.454805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-04T12:51:21.53164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.078968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.531651Z","caller":"traceutil/trace.go:171","msg":"trace[262781534] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"447.478186ms","start":"2026-10-04T12:51:21.084167Z","end":"2026-10-04T12:51:21.531645Z","steps":["trace[262781534] 'agreement among raft nodes before linearized reading' (duration: 447.430475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.084139Z","time spent":"447.525846ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-04T12:51:21.531669Z","caller":"traceutil/trace.go:171","msg":"trace[1254221913] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"505.138439ms","start":"2026-10-04T12:51:21.02652Z","end":"2026-10-04T12:51:21.531659Z","steps":["trace[1254221913] 'agreement among raft nodes before linearized reading' (duration: 505.099248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.026504Z","time spent":"505.20148ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-04T12:51:21.531747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.11444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.531764Z","caller":"traceutil/trace.go:171","msg":"trace[652441765] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"456.131091ms","start":"2026-10-04T12:51:21.075628Z","end":"2026-10-04T12:51:21.531759Z","steps":["trace[652441765] 'agreement among raft nodes before linearized reading' (duration: 456.10659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.072697Z","time spent":"458.520993ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.531794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.075574Z","time spent":"456.212922ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-04T12:51:21.531799Z","caller":"traceutil/trace.go:171","msg":"trace[936329941] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"448.287904ms","start":"2026-10-04T12:51:21.083501Z","end":"2026-10-04T12:51:21.531789Z","steps":["trace[936329941] 'process raft request' (duration: 447.336723ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.531787Z","caller":"traceutil/trace.go:171","msg":"trace[192102519] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"458.902222ms","start":"2026-10-04T12:51:21.072832Z","end":"2026-10-04T12:51:21.531735Z","steps":["trace[192102519] 'process raft request' (duration: 457.837158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.083483Z","time spent":"448.345645ms","remote":"127.0.0.1:42438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.531876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.36949ms","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-10-04T12:51:21.531899Z","caller":"traceutil/trace.go:171","msg":"trace[1726577910] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"458.432701ms","start":"2026-10-04T12:51:21.073456Z","end":"2026-10-04T12:51:21.531889Z","steps":["trace[1726577910] 'agreement among raft nodes before linearized reading' (duration: 458.405961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.073443Z","time spent":"458.469673ms","remote":"127.0.0.1:42346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-10-04T12:51:21.531754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.072833Z","time spent":"458.83145ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.532009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.867785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.112\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-04T12:51:21.531995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.125856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.532078Z","caller":"traceutil/trace.go:171","msg":"trace[122150492] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"352.208247ms","start":"2026-10-04T12:51:21.179838Z","end":"2026-10-04T12:51:21.532046Z","steps":["trace[122150492] 'agreement among raft nodes before linearized reading' (duration: 352.127455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.014632Z","time spent":"516.148587ms","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:51:21.532141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.179828Z","time spent":"352.302449ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-10-04T12:51:21.531938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.072819Z","time spent":"459.047166ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T12:51:21.532276Z","caller":"traceutil/trace.go:171","msg":"trace[1825041477] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"459.305551ms","start":"2026-10-04T12:51:21.07296Z","end":"2026-10-04T12:51:21.532266Z","steps":["trace[1825041477] 'process raft request' (duration: 457.796557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.113362Z","time spent":"418.018994ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.532321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.072908Z","time spent":"459.391674ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.532326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.106334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.532391Z","caller":"traceutil/trace.go:171","msg":"trace[1383326764] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"409.187616ms","start":"2026-10-04T12:51:21.123188Z","end":"2026-10-04T12:51:21.532375Z","steps":["trace[1383326764] 'agreement among raft nodes before linearized reading' (duration: 409.086824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.531587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.979594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-04T12:51:21.532423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.123179Z","time spent":"409.237567ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-04T12:51:21.532032Z","caller":"traceutil/trace.go:171","msg":"trace[1101517411] range","detail":"{range_begin:/registry/masterleases/162.253.55.112; range_end:; response_count:0; response_revision:11; }","duration":"460.923567ms","start":"2026-10-04T12:51:21.071103Z","end":"2026-10-04T12:51:21.532027Z","steps":["trace[1101517411] 'agreement among raft nodes before linearized reading' (duration: 460.892346ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.53245Z","caller":"traceutil/trace.go:171","msg":"trace[928345578] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"447.840874ms","start":"2026-10-04T12:51:21.084588Z","end":"2026-10-04T12:51:21.532429Z","steps":["trace[928345578] 'agreement among raft nodes before linearized reading' (duration: 446.820221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.532505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.071083Z","time spent":"461.415417ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.112\" "} {"level":"warn","ts":"2026-10-04T12:51:21.53252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.084562Z","time spent":"447.942536ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-04T12:51:21.532078Z","caller":"traceutil/trace.go:171","msg":"trace[790773073] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"457.572813ms","start":"2026-10-04T12:51:21.073612Z","end":"2026-10-04T12:51:21.531185Z","steps":["trace[790773073] 'process raft request' (duration: 457.168964ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.532748Z","caller":"traceutil/trace.go:171","msg":"trace[2066338934] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"459.948715ms","start":"2026-10-04T12:51:21.072783Z","end":"2026-10-04T12:51:21.532731Z","steps":["trace[2066338934] 'process raft request' (duration: 457.855748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.532862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.072778Z","time spent":"460.052928ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:51:21.533101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:51:21.073596Z","time spent":"459.195019ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T12:51:21.7285Z","caller":"traceutil/trace.go:171","msg":"trace[1342200733] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"189.434163ms","start":"2026-10-04T12:51:21.538999Z","end":"2026-10-04T12:51:21.728433Z","steps":["trace[1342200733] 'process raft request' (duration: 90.43399ms)","trace[1342200733] 'compare' (duration: 98.679266ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:21.728607Z","caller":"traceutil/trace.go:171","msg":"trace[1049955673] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"189.567206ms","start":"2026-10-04T12:51:21.539015Z","end":"2026-10-04T12:51:21.728583Z","steps":["trace[1049955673] 'process raft request' (duration: 189.30387ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728751Z","caller":"traceutil/trace.go:171","msg":"trace[720511881] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"188.379049ms","start":"2026-10-04T12:51:21.540361Z","end":"2026-10-04T12:51:21.72874Z","steps":["trace[720511881] 'process raft request' (duration: 188.012311ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728784Z","caller":"traceutil/trace.go:171","msg":"trace[2029447712] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"188.112523ms","start":"2026-10-04T12:51:21.540643Z","end":"2026-10-04T12:51:21.728755Z","steps":["trace[2029447712] 'process raft request' (duration: 187.886538ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728807Z","caller":"traceutil/trace.go:171","msg":"trace[590690432] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"187.703024ms","start":"2026-10-04T12:51:21.541088Z","end":"2026-10-04T12:51:21.728791Z","steps":["trace[590690432] 'process raft request' (duration: 187.53599ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728879Z","caller":"traceutil/trace.go:171","msg":"trace[1565576076] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"135.461152ms","start":"2026-10-04T12:51:21.593363Z","end":"2026-10-04T12:51:21.728824Z","steps":["trace[1565576076] 'read index received' (duration: 35.838365ms)","trace[1565576076] 'applied index is now lower than readState.Index' (duration: 99.620967ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:51:21.728918Z","caller":"traceutil/trace.go:171","msg":"trace[1131286577] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"188.330138ms","start":"2026-10-04T12:51:21.540579Z","end":"2026-10-04T12:51:21.728909Z","steps":["trace[1131286577] 'process raft request' (duration: 187.904528ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728966Z","caller":"traceutil/trace.go:171","msg":"trace[1623873015] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"185.097216ms","start":"2026-10-04T12:51:21.543814Z","end":"2026-10-04T12:51:21.728911Z","steps":["trace[1623873015] 'process raft request' (duration: 184.903141ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.728991Z","caller":"traceutil/trace.go:171","msg":"trace[1877108439] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"186.897676ms","start":"2026-10-04T12:51:21.542085Z","end":"2026-10-04T12:51:21.728983Z","steps":["trace[1877108439] 'process raft request' (duration: 186.583149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.729122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.722075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-04T12:51:21.729142Z","caller":"traceutil/trace.go:171","msg":"trace[156328184] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"188.302018ms","start":"2026-10-04T12:51:21.540824Z","end":"2026-10-04T12:51:21.729126Z","steps":["trace[156328184] 'process raft request' (duration: 187.745735ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.729179Z","caller":"traceutil/trace.go:171","msg":"trace[305137157] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"134.820487ms","start":"2026-10-04T12:51:21.594346Z","end":"2026-10-04T12:51:21.729167Z","steps":["trace[305137157] 'agreement among raft nodes before linearized reading' (duration: 134.708415ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:21.729214Z","caller":"traceutil/trace.go:171","msg":"trace[1773805751] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"185.266269ms","start":"2026-10-04T12:51:21.543935Z","end":"2026-10-04T12:51:21.729201Z","steps":["trace[1773805751] 'process raft request' (duration: 184.84564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:21.729262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.898291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T12:51:21.729296Z","caller":"traceutil/trace.go:171","msg":"trace[661966716] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"135.964723ms","start":"2026-10-04T12:51:21.593322Z","end":"2026-10-04T12:51:21.729287Z","steps":["trace[661966716] 'agreement among raft nodes before linearized reading' (duration: 135.920932ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:57.829387Z","caller":"traceutil/trace.go:171","msg":"trace[935396763] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"136.660108ms","start":"2026-10-04T12:51:57.692693Z","end":"2026-10-04T12:51:57.829353Z","steps":["trace[935396763] 'process raft request' (duration: 136.347741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:02.345241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.127059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:52:02.345408Z","caller":"traceutil/trace.go:171","msg":"trace[2125705200] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"182.16348ms","start":"2026-10-04T12:52:02.163229Z","end":"2026-10-04T12:52:02.345392Z","steps":["trace[2125705200] 'process raft request' (duration: 182.104939ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:52:02.345484Z","caller":"traceutil/trace.go:171","msg":"trace[73826966] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"213.446102ms","start":"2026-10-04T12:52:02.131988Z","end":"2026-10-04T12:52:02.345434Z","steps":["trace[73826966] 'process raft request' (duration: 79.887183ms)","trace[73826966] 'compare' (duration: 133.000767ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:52:02.808044Z","caller":"traceutil/trace.go:171","msg":"trace[1218423604] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"263.910776ms","start":"2026-10-04T12:52:02.544108Z","end":"2026-10-04T12:52:02.808019Z","steps":["trace[1218423604] 'process raft request' (duration: 263.340943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:03.67541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.791938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:52:03.675877Z","caller":"traceutil/trace.go:171","msg":"trace[2035654156] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"793.794173ms","start":"2026-10-04T12:52:02.88204Z","end":"2026-10-04T12:52:03.675834Z","steps":["trace[2035654156] 'process raft request' (duration: 95.162816ms)","trace[2035654156] 'compare' (duration: 697.610424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:52:03.676067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:02.882019Z","time spent":"793.939577ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:52:03.676094Z","caller":"traceutil/trace.go:171","msg":"trace[782487849] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"725.193443ms","start":"2026-10-04T12:52:02.95088Z","end":"2026-10-04T12:52:03.676073Z","steps":["trace[782487849] 'read index received' (duration: 26.320521ms)","trace[782487849] 'applied index is now lower than readState.Index' (duration: 698.871682ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:52:03.67645Z","caller":"traceutil/trace.go:171","msg":"trace[343752110] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"791.512103ms","start":"2026-10-04T12:52:02.884922Z","end":"2026-10-04T12:52:03.676434Z","steps":["trace[343752110] 'process raft request' (duration: 791.075743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:03.676564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:02.884894Z","time spent":"791.634015ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T12:52:03.676691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.116955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T12:52:03.67676Z","caller":"traceutil/trace.go:171","msg":"trace[379204515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"319.230208ms","start":"2026-10-04T12:52:03.357515Z","end":"2026-10-04T12:52:03.676745Z","steps":["trace[379204515] 'agreement among raft nodes before linearized reading' (duration: 319.065174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:03.676779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.3891ms","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-10-04T12:52:03.677261Z","caller":"traceutil/trace.go:171","msg":"trace[1463634727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"339.541644ms","start":"2026-10-04T12:52:03.337274Z","end":"2026-10-04T12:52:03.676816Z","steps":["trace[1463634727] 'agreement among raft nodes before linearized reading' (duration: 339.084584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:03.676795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:03.357445Z","time spent":"319.33949ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T12:52:03.67652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.648843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:52:03.677364Z","caller":"traceutil/trace.go:171","msg":"trace[1917290999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"726.515193ms","start":"2026-10-04T12:52:02.950818Z","end":"2026-10-04T12:52:03.677333Z","steps":["trace[1917290999] 'agreement among raft nodes before linearized reading' (duration: 725.561161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:03.677328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:03.337256Z","time spent":"340.054965ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T12:52:03.677451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:02.950801Z","time spent":"726.616655ms","remote":"127.0.0.1:42260","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-10-04T12:52:20.53069Z","caller":"traceutil/trace.go:171","msg":"trace[1334713783] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"318.947501ms","start":"2026-10-04T12:52:20.211716Z","end":"2026-10-04T12:52:20.530664Z","steps":["trace[1334713783] 'process raft request' (duration: 318.791148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:52:20.530927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:52:20.211689Z","time spent":"319.154896ms","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:53:01.944771Z","caller":"traceutil/trace.go:171","msg":"trace[1660218840] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"177.036705ms","start":"2026-10-04T12:53:01.767716Z","end":"2026-10-04T12:53:01.944752Z","steps":["trace[1660218840] 'process raft request' (duration: 176.879342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:02.177255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.934433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-04T12:53:02.177337Z","caller":"traceutil/trace.go:171","msg":"trace[1877903470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"108.072216ms","start":"2026-10-04T12:53:02.069246Z","end":"2026-10-04T12:53:02.177319Z","steps":["trace[1877903470] 'range keys from in-memory index tree' (duration: 107.76764ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:53:18.279357Z","caller":"traceutil/trace.go:171","msg":"trace[772390654] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"116.607229ms","start":"2026-10-04T12:53:18.162725Z","end":"2026-10-04T12:53:18.279332Z","steps":["trace[772390654] 'process raft request' (duration: 116.20674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:18.604648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.35891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:53:18.604889Z","caller":"traceutil/trace.go:171","msg":"trace[785825179] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"318.337812ms","start":"2026-10-04T12:53:18.286529Z","end":"2026-10-04T12:53:18.604867Z","steps":["trace[785825179] 'process raft request' (duration: 318.236499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:18.604955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:18.28647Z","time spent":"318.452724ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:53:18.604956Z","caller":"traceutil/trace.go:171","msg":"trace[168747437] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"318.324681ms","start":"2026-10-04T12:53:18.286599Z","end":"2026-10-04T12:53:18.604924Z","steps":["trace[168747437] 'process raft request' (duration: 318.230809ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:53:18.605018Z","caller":"traceutil/trace.go:171","msg":"trace[2129822604] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"319.641771ms","start":"2026-10-04T12:53:18.28533Z","end":"2026-10-04T12:53:18.604972Z","steps":["trace[2129822604] 'process raft request' (duration: 70.987385ms)","trace[2129822604] 'compare' (duration: 247.93284ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:53:18.605123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:18.286585Z","time spent":"318.447395ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:53:18.60511Z","caller":"traceutil/trace.go:171","msg":"trace[1737762850] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"319.025187ms","start":"2026-10-04T12:53:18.28602Z","end":"2026-10-04T12:53:18.605045Z","steps":["trace[1737762850] 'read index received' (duration: 70.311319ms)","trace[1737762850] 'applied index is now lower than readState.Index' (duration: 248.711978ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:53:18.605208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:18.285311Z","time spent":"319.793484ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T12:53:18.605243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.219952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-04T12:53:18.605295Z","caller":"traceutil/trace.go:171","msg":"trace[1565919643] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1157; }","duration":"319.297863ms","start":"2026-10-04T12:53:18.285983Z","end":"2026-10-04T12:53:18.605281Z","steps":["trace[1565919643] 'agreement among raft nodes before linearized reading' (duration: 319.16982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:18.605327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:18.285971Z","time spent":"319.348574ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-04T12:53:18.605333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.296559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pn6vn\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-10-04T12:53:18.605362Z","caller":"traceutil/trace.go:171","msg":"trace[1089701813] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pn6vn; range_end:; response_count:1; response_revision:1157; }","duration":"159.377481ms","start":"2026-10-04T12:53:18.445975Z","end":"2026-10-04T12:53:18.605352Z","steps":["trace[1089701813] 'agreement among raft nodes before linearized reading' (duration: 159.233577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:18.605376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.796855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T12:53:18.605457Z","caller":"traceutil/trace.go:171","msg":"trace[549981517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1157; }","duration":"149.886658ms","start":"2026-10-04T12:53:18.455516Z","end":"2026-10-04T12:53:18.605403Z","steps":["trace[549981517] 'agreement among raft nodes before linearized reading' (duration: 149.801976ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:53:18.884759Z","caller":"traceutil/trace.go:171","msg":"trace[1522782420] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"266.126369ms","start":"2026-10-04T12:53:18.618561Z","end":"2026-10-04T12:53:18.884687Z","steps":["trace[1522782420] 'process raft request' (duration: 265.896924ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:53:19.756915Z","caller":"traceutil/trace.go:171","msg":"trace[595373457] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"325.536303ms","start":"2026-10-04T12:53:19.431353Z","end":"2026-10-04T12:53:19.756889Z","steps":["trace[595373457] 'read index received' (duration: 325.41337ms)","trace[595373457] 'applied index is now lower than readState.Index' (duration: 121.613µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:53:19.75702Z","caller":"traceutil/trace.go:171","msg":"trace[65719349] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"327.273712ms","start":"2026-10-04T12:53:19.429712Z","end":"2026-10-04T12:53:19.756986Z","steps":["trace[65719349] 'process raft request' (duration: 326.975326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:19.757054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.664876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:53:19.757106Z","caller":"traceutil/trace.go:171","msg":"trace[1489775847] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1164; }","duration":"325.755379ms","start":"2026-10-04T12:53:19.431335Z","end":"2026-10-04T12:53:19.75709Z","steps":["trace[1489775847] 'agreement among raft nodes before linearized reading' (duration: 325.650106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:19.75715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:19.431328Z","time spent":"325.81034ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T12:53:19.7572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:53:19.429681Z","time spent":"327.422766ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:53:21.012908Z","caller":"traceutil/trace.go:171","msg":"trace[738206125] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"136.831594ms","start":"2026-10-04T12:53:20.876046Z","end":"2026-10-04T12:53:21.012878Z","steps":["trace[738206125] 'read index received' (duration: 37.551664ms)","trace[738206125] 'applied index is now lower than readState.Index' (duration: 99.279ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:53:21.012976Z","caller":"traceutil/trace.go:171","msg":"trace[1352327108] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"144.66356ms","start":"2026-10-04T12:53:20.868253Z","end":"2026-10-04T12:53:21.012917Z","steps":["trace[1352327108] 'process raft request' (duration: 45.439301ms)","trace[1352327108] 'compare' (duration: 99.056235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:53:21.013108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.047028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:53:21.013142Z","caller":"traceutil/trace.go:171","msg":"trace[1430120422] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1171; }","duration":"137.141401ms","start":"2026-10-04T12:53:20.875991Z","end":"2026-10-04T12:53:21.013132Z","steps":["trace[1430120422] 'agreement among raft nodes before linearized reading' (duration: 137.019538ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:53:34.908579Z","caller":"traceutil/trace.go:171","msg":"trace[1538610375] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"165.229302ms","start":"2026-10-04T12:53:34.743327Z","end":"2026-10-04T12:53:34.908557Z","steps":["trace[1538610375] 'read index received' (duration: 165.001786ms)","trace[1538610375] 'applied index is now lower than readState.Index' (duration: 226.606µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:53:34.908705Z","caller":"traceutil/trace.go:171","msg":"trace[1826155579] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"193.787474ms","start":"2026-10-04T12:53:34.714907Z","end":"2026-10-04T12:53:34.908694Z","steps":["trace[1826155579] 'process raft request' (duration: 193.496987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:34.908842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.102899ms","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-10-04T12:53:34.908895Z","caller":"traceutil/trace.go:171","msg":"trace[2000894791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"161.228972ms","start":"2026-10-04T12:53:34.747652Z","end":"2026-10-04T12:53:34.908881Z","steps":["trace[2000894791] 'agreement among raft nodes before linearized reading' (duration: 161.078188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:53:34.909311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.939497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T12:53:34.909423Z","caller":"traceutil/trace.go:171","msg":"trace[1238935254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"166.103441ms","start":"2026-10-04T12:53:34.743294Z","end":"2026-10-04T12:53:34.909398Z","steps":["trace[1238935254] 'agreement among raft nodes before linearized reading' (duration: 165.666762ms)"],"step_count":1}