{"level":"warn","ts":"2026-03-01T01:17:03.223601Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T01:17:03.223673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-03-01T01:17:03.223729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T01:17:03.22374Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-01T01:17:03.223757Z","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-03-01T01:17:03.224358Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-01T01:17:03.224428Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-03-01T01:17:03.228866Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.273621ms"} {"level":"info","ts":"2026-03-01T01:17:03.237237Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-01T01:17:03.2373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T01:17:03.237318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-01T01:17:03.237356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T01:17:03.237362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-01T01:17:03.237395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-01T01:17:03.246473Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T01:17:03.24792Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T01:17:03.249005Z","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-03-01T01:17:03.250807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T01:17:03.250986Z","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-03-01T01:17:03.251105Z","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-03-01T01:17:03.251118Z","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-03-01T01:17:03.254438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-01T01:17:03.25146Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T01:17:03.256441Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-01T01:17:03.257559Z","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-03-01T01:17:03.257634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-01T01:17:03.257671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-01T01:17:03.257784Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T01:17:03.257821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T01:17:03.738235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T01:17:03.738299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T01:17:03.738326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-01T01:17:03.738352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-01T01:17:03.73837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-01T01:17:03.738396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-01T01:17:03.73841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-01T01:17:03.739805Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T01:17:03.740909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T01:17:03.740928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T01:17:03.740901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T01:17:03.741578Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T01:17:03.741603Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T01:17:03.744341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-03-01T01:17:03.744588Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T01:17:03.759736Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T01:17:03.759836Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T01:17:03.759938Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-01T01:17:08.788154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.781444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T01:17:08.788286Z","caller":"traceutil/trace.go:171","msg":"trace[1403883520] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"239.246019ms","start":"2026-03-01T01:17:08.54902Z","end":"2026-03-01T01:17:08.788266Z","steps":["trace[1403883520] 'process raft request' (duration: 72.845151ms)","trace[1403883520] 'compare' (duration: 165.627813ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:08.815193Z","caller":"traceutil/trace.go:171","msg":"trace[2004032016] transaction","detail":"{read_only:false; number_of_response:0; response_revision:233; }","duration":"265.686889ms","start":"2026-03-01T01:17:08.549486Z","end":"2026-03-01T01:17:08.815173Z","steps":["trace[2004032016] 'process raft request' (duration: 265.487437ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.815271Z","caller":"traceutil/trace.go:171","msg":"trace[1500528375] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"239.179488ms","start":"2026-03-01T01:17:08.576073Z","end":"2026-03-01T01:17:08.815253Z","steps":["trace[1500528375] 'process raft request' (duration: 239.152398ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.815326Z","caller":"traceutil/trace.go:171","msg":"trace[1173432980] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"240.242907ms","start":"2026-03-01T01:17:08.575053Z","end":"2026-03-01T01:17:08.815296Z","steps":["trace[1173432980] 'process raft request' (duration: 239.986815ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.815318Z","caller":"traceutil/trace.go:171","msg":"trace[1908635070] transaction","detail":"{read_only:false; number_of_response:0; response_revision:234; }","duration":"239.635133ms","start":"2026-03-01T01:17:08.57564Z","end":"2026-03-01T01:17:08.815275Z","steps":["trace[1908635070] 'process raft request' (duration: 239.529622ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.81535Z","caller":"traceutil/trace.go:171","msg":"trace[1945323187] transaction","detail":"{read_only:false; number_of_response:0; response_revision:234; }","duration":"240.145886ms","start":"2026-03-01T01:17:08.57517Z","end":"2026-03-01T01:17:08.815316Z","steps":["trace[1945323187] 'process raft request' (duration: 239.931124ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.815384Z","caller":"traceutil/trace.go:171","msg":"trace[1401072449] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"239.419151ms","start":"2026-03-01T01:17:08.575925Z","end":"2026-03-01T01:17:08.815344Z","steps":["trace[1401072449] 'process raft request' (duration: 239.26806ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.879353Z","caller":"traceutil/trace.go:171","msg":"trace[839443063] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:239; }","duration":"159.651611ms","start":"2026-03-01T01:17:08.719684Z","end":"2026-03-01T01:17:08.879336Z","steps":["trace[839443063] 'read index received' (duration: 95.312752ms)","trace[839443063] 'applied index is now lower than readState.Index' (duration: 64.338229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T01:17:08.879567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.810712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:17:08.879627Z","caller":"traceutil/trace.go:171","msg":"trace[1418863575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:237; }","duration":"159.976403ms","start":"2026-03-01T01:17:08.719635Z","end":"2026-03-01T01:17:08.879611Z","steps":["trace[1418863575] 'agreement among raft nodes before linearized reading' (duration: 159.762832ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:08.879838Z","caller":"traceutil/trace.go:171","msg":"trace[1276878370] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"251.85425ms","start":"2026-03-01T01:17:08.627946Z","end":"2026-03-01T01:17:08.8798Z","steps":["trace[1276878370] 'process raft request' (duration: 251.273265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:09.053802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.285822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T01:17:09.053928Z","caller":"traceutil/trace.go:171","msg":"trace[2087062361] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"170.022573ms","start":"2026-03-01T01:17:08.883885Z","end":"2026-03-01T01:17:09.053908Z","steps":["trace[2087062361] 'process raft request' (duration: 33.542829ms)","trace[2087062361] 'compare' (duration: 136.145241ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:09.059281Z","caller":"traceutil/trace.go:171","msg":"trace[870176827] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"167.780407ms","start":"2026-03-01T01:17:08.891484Z","end":"2026-03-01T01:17:09.059264Z","steps":["trace[870176827] 'process raft request' (duration: 167.724157ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:09.059431Z","caller":"traceutil/trace.go:171","msg":"trace[942340523] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"169.953983ms","start":"2026-03-01T01:17:08.889469Z","end":"2026-03-01T01:17:09.059423Z","steps":["trace[942340523] 'process raft request' (duration: 169.699721ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:09.059382Z","caller":"traceutil/trace.go:171","msg":"trace[942481394] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"175.14353ms","start":"2026-03-01T01:17:08.884218Z","end":"2026-03-01T01:17:09.059361Z","steps":["trace[942481394] 'process raft request' (duration: 174.875238ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:09.256691Z","caller":"traceutil/trace.go:171","msg":"trace[2036394194] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"132.569893ms","start":"2026-03-01T01:17:09.124086Z","end":"2026-03-01T01:17:09.256655Z","steps":["trace[2036394194] 'process raft request' (duration: 132.305141ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:09.256719Z","caller":"traceutil/trace.go:171","msg":"trace[603895973] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"134.712869ms","start":"2026-03-01T01:17:09.121982Z","end":"2026-03-01T01:17:09.256695Z","steps":["trace[603895973] 'process raft request' (duration: 67.550361ms)","trace[603895973] 'compare' (duration: 66.504573ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:09.72485Z","caller":"traceutil/trace.go:171","msg":"trace[1474888584] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"110.223294ms","start":"2026-03-01T01:17:09.614609Z","end":"2026-03-01T01:17:09.724832Z","steps":["trace[1474888584] 'process raft request' (duration: 110.053573ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:09.724936Z","caller":"traceutil/trace.go:171","msg":"trace[1868978750] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"118.825696ms","start":"2026-03-01T01:17:09.606082Z","end":"2026-03-01T01:17:09.724908Z","steps":["trace[1868978750] 'process raft request' (duration: 39.804673ms)","trace[1868978750] 'compare' (duration: 78.55965ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:09.979186Z","caller":"traceutil/trace.go:171","msg":"trace[98276810] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"217.284836ms","start":"2026-03-01T01:17:09.761869Z","end":"2026-03-01T01:17:09.979153Z","steps":["trace[98276810] 'process raft request' (duration: 132.607223ms)","trace[98276810] 'compare' (duration: 84.449181ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:09.979295Z","caller":"traceutil/trace.go:171","msg":"trace[2058010000] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"207.342435ms","start":"2026-03-01T01:17:09.771925Z","end":"2026-03-01T01:17:09.979268Z","steps":["trace[2058010000] 'process raft request' (duration: 207.140584ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.339046Z","caller":"traceutil/trace.go:171","msg":"trace[1644745441] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"186.663455ms","start":"2026-03-01T01:17:14.15237Z","end":"2026-03-01T01:17:14.339034Z","steps":["trace[1644745441] 'process raft request' (duration: 148.944538ms)","trace[1644745441] 'compare' (duration: 37.467648ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:14.33927Z","caller":"traceutil/trace.go:171","msg":"trace[1523335752] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"186.340512ms","start":"2026-03-01T01:17:14.152917Z","end":"2026-03-01T01:17:14.339257Z","steps":["trace[1523335752] 'process raft request' (duration: 185.957638ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.338994Z","caller":"traceutil/trace.go:171","msg":"trace[1483461454] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:323; }","duration":"174.331621ms","start":"2026-03-01T01:17:14.164643Z","end":"2026-03-01T01:17:14.338975Z","steps":["trace[1483461454] 'read index received' (duration: 71.901654ms)","trace[1483461454] 'applied index is now lower than readState.Index' (duration: 102.428957ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T01:17:14.339527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.875421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-01T01:17:14.339609Z","caller":"traceutil/trace.go:171","msg":"trace[1411010521] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:315; }","duration":"175.019337ms","start":"2026-03-01T01:17:14.164572Z","end":"2026-03-01T01:17:14.339592Z","steps":["trace[1411010521] 'agreement among raft nodes before linearized reading' (duration: 174.883332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:14.389458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.861552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-01T01:17:14.389543Z","caller":"traceutil/trace.go:171","msg":"trace[728895051] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:316; }","duration":"124.970916ms","start":"2026-03-01T01:17:14.264554Z","end":"2026-03-01T01:17:14.389525Z","steps":["trace[728895051] 'agreement among raft nodes before linearized reading' (duration: 124.816261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:14.389623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.976539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-01T01:17:14.389724Z","caller":"traceutil/trace.go:171","msg":"trace[1299560976] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"150.340009ms","start":"2026-03-01T01:17:14.239348Z","end":"2026-03-01T01:17:14.389688Z","steps":["trace[1299560976] 'process raft request' (duration: 149.877552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:14.389814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.77676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-01T01:17:14.389808Z","caller":"traceutil/trace.go:171","msg":"trace[1582206745] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:316; }","duration":"174.123954ms","start":"2026-03-01T01:17:14.215583Z","end":"2026-03-01T01:17:14.389707Z","steps":["trace[1582206745] 'agreement among raft nodes before linearized reading' (duration: 173.954218ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.389853Z","caller":"traceutil/trace.go:171","msg":"trace[1845867389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:316; }","duration":"192.836632ms","start":"2026-03-01T01:17:14.197007Z","end":"2026-03-01T01:17:14.389844Z","steps":["trace[1845867389] 'agreement among raft nodes before linearized reading' (duration: 192.755839ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.516231Z","caller":"traceutil/trace.go:171","msg":"trace[1347009706] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"126.883046ms","start":"2026-03-01T01:17:14.389325Z","end":"2026-03-01T01:17:14.516208Z","steps":["trace[1347009706] 'read index received' (duration: 96.125615ms)","trace[1347009706] 'applied index is now lower than readState.Index' (duration: 30.756431ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:14.516239Z","caller":"traceutil/trace.go:171","msg":"trace[2097254546] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"169.198272ms","start":"2026-03-01T01:17:14.347013Z","end":"2026-03-01T01:17:14.516211Z","steps":["trace[2097254546] 'process raft request' (duration: 138.427391ms)","trace[2097254546] 'compare' (duration: 30.593945ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:14.51638Z","caller":"traceutil/trace.go:171","msg":"trace[1999779311] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"168.017159ms","start":"2026-03-01T01:17:14.34834Z","end":"2026-03-01T01:17:14.516357Z","steps":["trace[1999779311] 'process raft request' (duration: 167.812561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:14.516549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.424612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-01T01:17:14.516486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.06305ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:17:14.516634Z","caller":"traceutil/trace.go:171","msg":"trace[371804344] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:318; }","duration":"150.556697ms","start":"2026-03-01T01:17:14.366059Z","end":"2026-03-01T01:17:14.516615Z","steps":["trace[371804344] 'agreement among raft nodes before linearized reading' (duration: 150.386961ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.516722Z","caller":"traceutil/trace.go:171","msg":"trace[2069869090] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:318; }","duration":"128.291708ms","start":"2026-03-01T01:17:14.388382Z","end":"2026-03-01T01:17:14.516674Z","steps":["trace[2069869090] 'agreement among raft nodes before linearized reading' (duration: 127.968406ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.525161Z","caller":"traceutil/trace.go:171","msg":"trace[1943404367] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"131.198115ms","start":"2026-03-01T01:17:14.393946Z","end":"2026-03-01T01:17:14.525144Z","steps":["trace[1943404367] 'process raft request' (duration: 130.980747ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.525733Z","caller":"traceutil/trace.go:171","msg":"trace[773825498] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"129.050186ms","start":"2026-03-01T01:17:14.396672Z","end":"2026-03-01T01:17:14.525722Z","steps":["trace[773825498] 'process raft request' (duration: 128.401553ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.526134Z","caller":"traceutil/trace.go:171","msg":"trace[1245986165] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"125.840449ms","start":"2026-03-01T01:17:14.400217Z","end":"2026-03-01T01:17:14.526058Z","steps":["trace[1245986165] 'process raft request' (duration: 125.441104ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:14.526344Z","caller":"traceutil/trace.go:171","msg":"trace[1339710187] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"126.508633ms","start":"2026-03-01T01:17:14.399823Z","end":"2026-03-01T01:17:14.526331Z","steps":["trace[1339710187] 'process raft request' (duration: 125.774966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:14.526204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.568624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-01T01:17:14.526433Z","caller":"traceutil/trace.go:171","msg":"trace[809493176] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:322; }","duration":"111.805082ms","start":"2026-03-01T01:17:14.41462Z","end":"2026-03-01T01:17:14.526425Z","steps":["trace[809493176] 'agreement among raft nodes before linearized reading' (duration: 111.516012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:21.882984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.222944ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-01T01:17:21.902648Z","caller":"traceutil/trace.go:171","msg":"trace[739118844] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"126.405402ms","start":"2026-03-01T01:17:21.776219Z","end":"2026-03-01T01:17:21.902624Z","steps":["trace[739118844] 'process raft request' (duration: 126.242687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:25.827831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.691664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T01:17:25.827917Z","caller":"traceutil/trace.go:171","msg":"trace[1896710744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"167.825379ms","start":"2026-03-01T01:17:25.660069Z","end":"2026-03-01T01:17:25.827895Z","steps":["trace[1896710744] 'range keys from in-memory index tree' (duration: 167.532979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:25.828167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.344989ms","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-03-01T01:17:25.82834Z","caller":"traceutil/trace.go:171","msg":"trace[152926031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"437.599708ms","start":"2026-03-01T01:17:25.390709Z","end":"2026-03-01T01:17:25.828309Z","steps":["trace[152926031] 'range keys from in-memory index tree' (duration: 437.129042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:25.828402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:25.39068Z","time spent":"437.710381ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-01T01:17:31.218093Z","caller":"traceutil/trace.go:171","msg":"trace[2086307070] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"527.195485ms","start":"2026-03-01T01:17:30.69087Z","end":"2026-03-01T01:17:31.218066Z","steps":["trace[2086307070] 'process raft request' (duration: 527.050881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:31.218655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:30.690857Z","time spent":"527.32647ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T01:17:31.757096Z","caller":"traceutil/trace.go:171","msg":"trace[1625712072] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"312.03221ms","start":"2026-03-01T01:17:31.445046Z","end":"2026-03-01T01:17:31.757078Z","steps":["trace[1625712072] 'process raft request' (duration: 311.895926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:31.757216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:31.445025Z","time spent":"312.125052ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T01:17:35.592582Z","caller":"traceutil/trace.go:171","msg":"trace[1753955438] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"352.556996ms","start":"2026-03-01T01:17:35.239994Z","end":"2026-03-01T01:17:35.592551Z","steps":["trace[1753955438] 'process raft request' (duration: 352.323579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:35.592797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:35.239957Z","time spent":"352.72215ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T01:17:37.139978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:36.667513Z","time spent":"472.460224ms","remote":"127.0.0.1:39714","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-01T01:17:43.731222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.032307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T01:17:43.731286Z","caller":"traceutil/trace.go:171","msg":"trace[2076972526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"109.14604ms","start":"2026-03-01T01:17:43.622124Z","end":"2026-03-01T01:17:43.73127Z","steps":["trace[2076972526] 'range keys from in-memory index tree' (duration: 108.819911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:43.73129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.138659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmt57\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-01T01:17:43.731377Z","caller":"traceutil/trace.go:171","msg":"trace[35193215] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmt57; range_end:; response_count:1; response_revision:512; }","duration":"166.286043ms","start":"2026-03-01T01:17:43.56507Z","end":"2026-03-01T01:17:43.731356Z","steps":["trace[35193215] 'range keys from in-memory index tree' (duration: 165.944084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.308943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133065034959231,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T01:17:44.6164Z","caller":"traceutil/trace.go:171","msg":"trace[1820216355] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"808.434913ms","start":"2026-03-01T01:17:43.80794Z","end":"2026-03-01T01:17:44.616375Z","steps":["trace[1820216355] 'read index received' (duration: 808.226628ms)","trace[1820216355] 'applied index is now lower than readState.Index' (duration: 207.115µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:44.616529Z","caller":"traceutil/trace.go:171","msg":"trace[1601142873] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"873.13618ms","start":"2026-03-01T01:17:43.743384Z","end":"2026-03-01T01:17:44.61652Z","steps":["trace[1601142873] 'process raft request' (duration: 872.834442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.616645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:43.743377Z","time spent":"873.184871ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T01:17:44.61689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.330108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T01:17:44.616965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.005048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T01:17:44.617057Z","caller":"traceutil/trace.go:171","msg":"trace[1750129724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"809.120572ms","start":"2026-03-01T01:17:43.807909Z","end":"2026-03-01T01:17:44.61703Z","steps":["trace[1750129724] 'agreement among raft nodes before linearized reading' (duration: 808.813383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.616945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.104966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-01T01:17:44.617111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:43.807898Z","time spent":"809.204183ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T01:17:44.617171Z","caller":"traceutil/trace.go:171","msg":"trace[348034174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"684.336012ms","start":"2026-03-01T01:17:43.932782Z","end":"2026-03-01T01:17:44.617118Z","steps":["trace[348034174] 'agreement among raft nodes before linearized reading' (duration: 684.005413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.617235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:43.932774Z","time spent":"684.445476ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-01T01:17:44.617049Z","caller":"traceutil/trace.go:171","msg":"trace[338023983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"257.537734ms","start":"2026-03-01T01:17:44.359479Z","end":"2026-03-01T01:17:44.617016Z","steps":["trace[338023983] 'agreement among raft nodes before linearized reading' (duration: 257.314428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.617759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.69029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T01:17:44.61781Z","caller":"traceutil/trace.go:171","msg":"trace[355819324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"691.783942ms","start":"2026-03-01T01:17:43.926015Z","end":"2026-03-01T01:17:44.617799Z","steps":["trace[355819324] 'agreement among raft nodes before linearized reading' (duration: 691.612337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.617849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:43.925993Z","time spent":"691.848793ms","remote":"127.0.0.1:39970","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":"info","ts":"2026-03-01T01:17:44.848474Z","caller":"traceutil/trace.go:171","msg":"trace[1202330555] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"226.93173ms","start":"2026-03-01T01:17:44.621523Z","end":"2026-03-01T01:17:44.848455Z","steps":["trace[1202330555] 'process raft request' (duration: 226.802857ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:44.861248Z","caller":"traceutil/trace.go:171","msg":"trace[2248777] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"239.508825ms","start":"2026-03-01T01:17:44.621721Z","end":"2026-03-01T01:17:44.86123Z","steps":["trace[2248777] 'process raft request' (duration: 239.375041ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:44.861397Z","caller":"traceutil/trace.go:171","msg":"trace[1841643070] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"173.816869ms","start":"2026-03-01T01:17:44.687562Z","end":"2026-03-01T01:17:44.861379Z","steps":["trace[1841643070] 'read index received' (duration: 160.723691ms)","trace[1841643070] 'applied index is now lower than readState.Index' (duration: 13.092038ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:44.861515Z","caller":"traceutil/trace.go:171","msg":"trace[1564522829] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"237.712387ms","start":"2026-03-01T01:17:44.623796Z","end":"2026-03-01T01:17:44.861508Z","steps":["trace[1564522829] 'process raft request' (duration: 237.366608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:44.861519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.953193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:17:44.861563Z","caller":"traceutil/trace.go:171","msg":"trace[550652264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"174.063075ms","start":"2026-03-01T01:17:44.68749Z","end":"2026-03-01T01:17:44.861553Z","steps":["trace[550652264] 'agreement among raft nodes before linearized reading' (duration: 173.953692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.167579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133065034959241,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T01:17:47.215602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40635346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T01:17:47.215654Z","caller":"traceutil/trace.go:171","msg":"trace[538332520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.406439822s","start":"2026-03-01T01:17:45.809201Z","end":"2026-03-01T01:17:47.215641Z","steps":["trace[538332520] 'range keys from in-memory index tree' (duration: 1.406217356s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.215676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:45.809183Z","time spent":"1.406489484s","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T01:17:47.215921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530435134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:17:47.215973Z","caller":"traceutil/trace.go:171","msg":"trace[1992553762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.530488445s","start":"2026-03-01T01:17:45.685473Z","end":"2026-03-01T01:17:47.215961Z","steps":["trace[1992553762] 'range keys from in-memory index tree' (duration: 1.530311501s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.216012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:45.685378Z","time spent":"1.530626579s","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T01:17:47.232755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.157909204s","expected-duration":"1s"} {"level":"info","ts":"2026-03-01T01:17:47.232953Z","caller":"traceutil/trace.go:171","msg":"trace[1957789232] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"565.445161ms","start":"2026-03-01T01:17:46.6675Z","end":"2026-03-01T01:17:47.232945Z","steps":["trace[1957789232] 'read index received' (duration: 565.352858ms)","trace[1957789232] 'applied index is now lower than readState.Index' (duration: 91.803µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:47.233055Z","caller":"traceutil/trace.go:171","msg":"trace[1131625334] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.158284453s","start":"2026-03-01T01:17:46.074765Z","end":"2026-03-01T01:17:47.233049Z","steps":["trace[1131625334] 'process raft request' (duration: 1.158121869s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.233115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:46.074738Z","time spent":"1.158335995s","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T01:17:47.233244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.751308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-01T01:17:47.233264Z","caller":"traceutil/trace.go:171","msg":"trace[1759863528] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:518; }","duration":"565.771359ms","start":"2026-03-01T01:17:46.667488Z","end":"2026-03-01T01:17:47.23326Z","steps":["trace[1759863528] 'agreement among raft nodes before linearized reading' (duration: 565.736498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.233278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:46.667442Z","time spent":"565.83272ms","remote":"127.0.0.1:39714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-03-01T01:17:47.233288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.686137ms","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-03-01T01:17:47.23333Z","caller":"traceutil/trace.go:171","msg":"trace[1803559551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"380.775629ms","start":"2026-03-01T01:17:46.852543Z","end":"2026-03-01T01:17:47.233318Z","steps":["trace[1803559551] 'agreement among raft nodes before linearized reading' (duration: 380.613525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.233369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:46.852522Z","time spent":"380.841911ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T01:17:47.233511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.945805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T01:17:47.233525Z","caller":"traceutil/trace.go:171","msg":"trace[596544342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"367.988516ms","start":"2026-03-01T01:17:46.865532Z","end":"2026-03-01T01:17:47.23352Z","steps":["trace[596544342] 'agreement among raft nodes before linearized reading' (duration: 367.943535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.233538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:46.865517Z","time spent":"368.017828ms","remote":"127.0.0.1:39970","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-03-01T01:17:47.233585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.151831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T01:17:47.233608Z","caller":"traceutil/trace.go:171","msg":"trace[1510608130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"368.213923ms","start":"2026-03-01T01:17:46.865387Z","end":"2026-03-01T01:17:47.233601Z","steps":["trace[1510608130] 'agreement among raft nodes before linearized reading' (duration: 368.144391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:47.233625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:46.865365Z","time spent":"368.256394ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T01:17:47.733838Z","caller":"traceutil/trace.go:171","msg":"trace[763554732] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"141.794358ms","start":"2026-03-01T01:17:47.592015Z","end":"2026-03-01T01:17:47.73381Z","steps":["trace[763554732] 'process raft request' (duration: 141.026118ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:17:49.312077Z","caller":"traceutil/trace.go:171","msg":"trace[1454393097] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"444.006804ms","start":"2026-03-01T01:17:48.868042Z","end":"2026-03-01T01:17:49.312049Z","steps":["trace[1454393097] 'read index received' (duration: 443.711947ms)","trace[1454393097] 'applied index is now lower than readState.Index' (duration: 293.507µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T01:17:49.312118Z","caller":"traceutil/trace.go:171","msg":"trace[1980576865] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"559.217706ms","start":"2026-03-01T01:17:48.752873Z","end":"2026-03-01T01:17:49.312091Z","steps":["trace[1980576865] 'process raft request' (duration: 558.951539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:49.312323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:48.752864Z","time spent":"559.33216ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T01:17:49.312362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.283071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:17:49.312409Z","caller":"traceutil/trace.go:171","msg":"trace[1860177883] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"444.360173ms","start":"2026-03-01T01:17:48.868034Z","end":"2026-03-01T01:17:49.312395Z","steps":["trace[1860177883] 'agreement among raft nodes before linearized reading' (duration: 444.25295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:49.312387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.170771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T01:17:49.31245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T01:17:48.867982Z","time spent":"444.458725ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-01T01:17:49.312488Z","caller":"traceutil/trace.go:171","msg":"trace[1979028705] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"243.305614ms","start":"2026-03-01T01:17:49.069147Z","end":"2026-03-01T01:17:49.312452Z","steps":["trace[1979028705] 'agreement among raft nodes before linearized reading' (duration: 243.188061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T01:17:49.312894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.200232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-nv8qd\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-03-01T01:17:49.312924Z","caller":"traceutil/trace.go:171","msg":"trace[920639037] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-nv8qd; range_end:; response_count:1; response_revision:554; }","duration":"177.269124ms","start":"2026-03-01T01:17:49.135648Z","end":"2026-03-01T01:17:49.312917Z","steps":["trace[920639037] 'agreement among raft nodes before linearized reading' (duration: 177.191922ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:19:06.81216Z","caller":"traceutil/trace.go:171","msg":"trace[1460615867] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"125.850672ms","start":"2026-03-01T01:19:06.686283Z","end":"2026-03-01T01:19:06.812133Z","steps":["trace[1460615867] 'read index received' (duration: 125.67135ms)","trace[1460615867] 'applied index is now lower than readState.Index' (duration: 177.832µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T01:19:06.812325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.020785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T01:19:06.812366Z","caller":"traceutil/trace.go:171","msg":"trace[1386019528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"126.087006ms","start":"2026-03-01T01:19:06.686267Z","end":"2026-03-01T01:19:06.812354Z","steps":["trace[1386019528] 'agreement among raft nodes before linearized reading' (duration: 126.007464ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:19:13.928065Z","caller":"traceutil/trace.go:171","msg":"trace[2143858221] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"122.975781ms","start":"2026-03-01T01:19:13.805065Z","end":"2026-03-01T01:19:13.928041Z","steps":["trace[2143858221] 'process raft request' (duration: 122.929701ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T01:19:13.928471Z","caller":"traceutil/trace.go:171","msg":"trace[14337732] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"123.484149ms","start":"2026-03-01T01:19:13.804954Z","end":"2026-03-01T01:19:13.928438Z","steps":["trace[14337732] 'process raft request' (duration: 122.908901ms)"],"step_count":1}