{"level":"warn","ts":"2026-03-09T06:10:53.772972Z","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-09T06:10:53.773119Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-09T06:10:53.773237Z","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-09T06:10:53.773263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-09T06:10:53.773299Z","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-09T06:10:53.774292Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-03-09T06:10:53.774401Z","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.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-09T06:10:53.802376Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"27.667272ms"} {"level":"info","ts":"2026-03-09T06:10:53.820263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-03-09T06:10:53.820347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T06:10:53.820371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-03-09T06:10:53.820381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T06:10:53.820389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-03-09T06:10:53.820427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-03-09T06:10:53.830983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T06:10:53.832718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T06:10:53.834476Z","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-09T06:10:53.837801Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T06:10:53.837975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T06:10:53.83806Z","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-09T06:10:53.838186Z","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-09T06:10:53.838197Z","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-09T06:10:53.840936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-03-09T06:10:53.848203Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-09T06:10:53.849464Z","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-09T06:10:53.849646Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T06:10:53.849666Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T06:10:53.849774Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-09T06:10:53.849906Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-09T06:10:54.221367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T06:10:54.221425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T06:10:54.221453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-03-09T06:10:54.221468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-03-09T06:10:54.221475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-09T06:10:54.221487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-03-09T06:10:54.221497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-09T06:10:54.222499Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:10:54.223371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:10:54.223364Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T06:10:54.223403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:10:54.223816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T06:10:54.223865Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T06:10:54.223977Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:10:54.224059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:10:54.224086Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:10:54.225744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-03-09T06:10:54.226416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T06:10:55.940417Z","caller":"traceutil/trace.go:171","msg":"trace[247113129] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"123.711565ms","start":"2026-03-09T06:10:55.816679Z","end":"2026-03-09T06:10:55.94039Z","steps":["trace[247113129] 'process raft request' (duration: 123.622412ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:55.940576Z","caller":"traceutil/trace.go:171","msg":"trace[13611942] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"133.903436ms","start":"2026-03-09T06:10:55.806639Z","end":"2026-03-09T06:10:55.940543Z","steps":["trace[13611942] 'process raft request' (duration: 85.682761ms)","trace[13611942] 'compare' (duration: 47.833633ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:10:56.171916Z","caller":"traceutil/trace.go:171","msg":"trace[1716157492] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"127.481173ms","start":"2026-03-09T06:10:56.044406Z","end":"2026-03-09T06:10:56.171888Z","steps":["trace[1716157492] 'process raft request' (duration: 127.337168ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:56.172014Z","caller":"traceutil/trace.go:171","msg":"trace[1560878386] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"128.460394ms","start":"2026-03-09T06:10:56.043529Z","end":"2026-03-09T06:10:56.17199Z","steps":["trace[1560878386] 'process raft request' (duration: 114.763459ms)","trace[1560878386] 'compare' (duration: 13.332913ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:10:56.172097Z","caller":"traceutil/trace.go:171","msg":"trace[1499839456] linearizableReadLoop","detail":"{readStateIndex:35; appliedIndex:31; }","duration":"127.236514ms","start":"2026-03-09T06:10:56.044833Z","end":"2026-03-09T06:10:56.17207Z","steps":["trace[1499839456] 'read index received' (duration: 5.820285ms)","trace[1499839456] 'applied index is now lower than readState.Index' (duration: 121.415389ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:10:56.172314Z","caller":"traceutil/trace.go:171","msg":"trace[865149480] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"127.552123ms","start":"2026-03-09T06:10:56.044745Z","end":"2026-03-09T06:10:56.172297Z","steps":["trace[865149480] 'process raft request' (duration: 127.050948ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:56.172353Z","caller":"traceutil/trace.go:171","msg":"trace[116758402] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"127.42648ms","start":"2026-03-09T06:10:56.044903Z","end":"2026-03-09T06:10:56.172329Z","steps":["trace[116758402] 'process raft request' (duration: 126.987317ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:56.172483Z","caller":"traceutil/trace.go:171","msg":"trace[1788666948] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"126.711788ms","start":"2026-03-09T06:10:56.045755Z","end":"2026-03-09T06:10:56.172466Z","steps":["trace[1788666948] 'process raft request' (duration: 126.258594ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:56.172486Z","caller":"traceutil/trace.go:171","msg":"trace[2053033159] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"127.521493ms","start":"2026-03-09T06:10:56.044831Z","end":"2026-03-09T06:10:56.172352Z","steps":["trace[2053033159] 'process raft request' (duration: 127.012677ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:56.172492Z","caller":"traceutil/trace.go:171","msg":"trace[1742838809] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"127.192623ms","start":"2026-03-09T06:10:56.04524Z","end":"2026-03-09T06:10:56.172433Z","steps":["trace[1742838809] 'process raft request' (duration: 126.695777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:10:56.173016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.143563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-09T06:10:56.173542Z","caller":"traceutil/trace.go:171","msg":"trace[83819852] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:35; }","duration":"128.724751ms","start":"2026-03-09T06:10:56.044783Z","end":"2026-03-09T06:10:56.173508Z","steps":["trace[83819852] 'agreement among raft nodes before linearized reading' (duration: 127.43298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:10:59.4926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.647277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:10:59.492976Z","caller":"traceutil/trace.go:171","msg":"trace[919820436] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"196.60376ms","start":"2026-03-09T06:10:59.296311Z","end":"2026-03-09T06:10:59.492914Z","steps":["trace[919820436] 'process raft request' (duration: 196.386263ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.493059Z","caller":"traceutil/trace.go:171","msg":"trace[996256722] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"172.765839ms","start":"2026-03-09T06:10:59.320273Z","end":"2026-03-09T06:10:59.493039Z","steps":["trace[996256722] 'process raft request' (duration: 172.712488ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.493091Z","caller":"traceutil/trace.go:171","msg":"trace[354299746] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"178.10934ms","start":"2026-03-09T06:10:59.31497Z","end":"2026-03-09T06:10:59.49308Z","steps":["trace[354299746] 'process raft request' (duration: 177.835891ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.493136Z","caller":"traceutil/trace.go:171","msg":"trace[915186801] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"179.783564ms","start":"2026-03-09T06:10:59.313346Z","end":"2026-03-09T06:10:59.493129Z","steps":["trace[915186801] 'process raft request' (duration: 179.402161ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.49304Z","caller":"traceutil/trace.go:171","msg":"trace[2070742912] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"178.43835ms","start":"2026-03-09T06:10:59.314549Z","end":"2026-03-09T06:10:59.492988Z","steps":["trace[2070742912] 'process raft request' (duration: 178.229673ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.493373Z","caller":"traceutil/trace.go:171","msg":"trace[691716587] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"177.299545ms","start":"2026-03-09T06:10:59.316063Z","end":"2026-03-09T06:10:59.493362Z","steps":["trace[691716587] 'process raft request' (duration: 176.886691ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:10:59.493Z","caller":"traceutil/trace.go:171","msg":"trace[1386164348] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"197.489968ms","start":"2026-03-09T06:10:59.295443Z","end":"2026-03-09T06:10:59.492933Z","steps":["trace[1386164348] 'process raft request' (duration: 86.800558ms)","trace[1386164348] 'compare' (duration: 109.489472ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:10:59.49347Z","caller":"traceutil/trace.go:171","msg":"trace[1662940388] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"177.791561ms","start":"2026-03-09T06:10:59.31567Z","end":"2026-03-09T06:10:59.493461Z","steps":["trace[1662940388] 'process raft request' (duration: 177.17352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:00.018983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.037466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:11:00.019097Z","caller":"traceutil/trace.go:171","msg":"trace[2016403642] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:292; }","duration":"129.221112ms","start":"2026-03-09T06:10:59.88985Z","end":"2026-03-09T06:11:00.019072Z","steps":["trace[2016403642] 'range keys from in-memory index tree' (duration: 128.849461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:00.860867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.052903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-09T06:11:00.861055Z","caller":"traceutil/trace.go:171","msg":"trace[430807921] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"190.29732ms","start":"2026-03-09T06:11:00.670736Z","end":"2026-03-09T06:11:00.861034Z","steps":["trace[430807921] 'range keys from in-memory index tree' (duration: 189.927869ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:01.06504Z","caller":"traceutil/trace.go:171","msg":"trace[924128852] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"199.453867ms","start":"2026-03-09T06:11:00.865277Z","end":"2026-03-09T06:11:01.064731Z","steps":["trace[924128852] 'process raft request' (duration: 199.28701ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:01.080369Z","caller":"traceutil/trace.go:171","msg":"trace[649921153] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"215.067056ms","start":"2026-03-09T06:11:00.865277Z","end":"2026-03-09T06:11:01.080344Z","steps":["trace[649921153] 'process raft request' (duration: 214.934422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:02.628699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059869006s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:11:02.628844Z","caller":"traceutil/trace.go:171","msg":"trace[897295291] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"976.643342ms","start":"2026-03-09T06:11:01.652178Z","end":"2026-03-09T06:11:02.628821Z","steps":["trace[897295291] 'read index received' (duration: 52.011µs)","trace[897295291] 'applied index is now lower than readState.Index' (duration: 976.589771ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:02.62887Z","caller":"traceutil/trace.go:171","msg":"trace[180073922] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.539840458s","start":"2026-03-09T06:11:01.089001Z","end":"2026-03-09T06:11:02.628841Z","steps":["trace[180073922] 'process raft request' (duration: 479.701883ms)","trace[180073922] 'compare' (duration: 1.059687771s)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:11:02.628989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:01.088986Z","time spent":"1.539941842s","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T06:11:02.62905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.86122ms","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-09T06:11:02.629085Z","caller":"traceutil/trace.go:171","msg":"trace[1718114354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"976.979823ms","start":"2026-03-09T06:11:01.652093Z","end":"2026-03-09T06:11:02.629073Z","steps":["trace[1718114354] 'agreement among raft nodes before linearized reading' (duration: 976.845939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:02.629127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:01.65207Z","time spent":"977.048616ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T06:11:02.629301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.954881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-09T06:11:02.62933Z","caller":"traceutil/trace.go:171","msg":"trace[1297316718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"477.035114ms","start":"2026-03-09T06:11:02.152288Z","end":"2026-03-09T06:11:02.629323Z","steps":["trace[1297316718] 'agreement among raft nodes before linearized reading' (duration: 476.948401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:02.629348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.152263Z","time spent":"477.081835ms","remote":"127.0.0.1:42212","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-09T06:11:02.629652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.611735ms","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-03-09T06:11:02.629698Z","caller":"traceutil/trace.go:171","msg":"trace[2068371502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:313; }","duration":"333.696108ms","start":"2026-03-09T06:11:02.295989Z","end":"2026-03-09T06:11:02.629685Z","steps":["trace[2068371502] 'agreement among raft nodes before linearized reading' (duration: 333.389228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:02.629731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.29597Z","time spent":"333.75493ms","remote":"127.0.0.1:42212","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-09T06:11:03.983435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.272295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:11:03.983732Z","caller":"traceutil/trace.go:171","msg":"trace[138189673] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"1.348930649s","start":"2026-03-09T06:11:02.634774Z","end":"2026-03-09T06:11:03.983704Z","steps":["trace[138189673] 'read index received' (duration: 417.059274ms)","trace[138189673] 'applied index is now lower than readState.Index' (duration: 931.869765ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:03.983738Z","caller":"traceutil/trace.go:171","msg":"trace[559899786] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.350354065s","start":"2026-03-09T06:11:02.633346Z","end":"2026-03-09T06:11:03.9837Z","steps":["trace[559899786] 'process raft request' (duration: 418.51049ms)","trace[559899786] 'compare' (duration: 931.09412ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:11:03.983908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.633327Z","time spent":"1.350482879s","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:11:03.984063Z","caller":"traceutil/trace.go:171","msg":"trace[1263646615] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.349363163s","start":"2026-03-09T06:11:02.634683Z","end":"2026-03-09T06:11:03.984046Z","steps":["trace[1263646615] 'process raft request' (duration: 1.348887627s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:03.984224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.634668Z","time spent":"1.349499997s","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:11:03.984279Z","caller":"traceutil/trace.go:171","msg":"trace[2145008185] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"1.34833516s","start":"2026-03-09T06:11:02.635935Z","end":"2026-03-09T06:11:03.984271Z","steps":["trace[2145008185] 'process raft request' (duration: 1.347708119s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:03.984321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.635927Z","time spent":"1.348370831s","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:03.984249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349474106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-09T06:11:03.984455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.045235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T06:11:03.984374Z","caller":"traceutil/trace.go:171","msg":"trace[2072156103] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"1.349614721s","start":"2026-03-09T06:11:02.634737Z","end":"2026-03-09T06:11:03.984352Z","steps":["trace[2072156103] 'agreement among raft nodes before linearized reading' (duration: 1.349467926s)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:03.984568Z","caller":"traceutil/trace.go:171","msg":"trace[660926629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:316; }","duration":"915.234471ms","start":"2026-03-09T06:11:03.069309Z","end":"2026-03-09T06:11:03.984544Z","steps":["trace[660926629] 'agreement among raft nodes before linearized reading' (duration: 914.990933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:03.984634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:02.634724Z","time spent":"1.34989518s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-09T06:11:03.984662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:03.069283Z","time spent":"915.368675ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-09T06:11:04.340407Z","caller":"traceutil/trace.go:171","msg":"trace[451870288] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"223.805938ms","start":"2026-03-09T06:11:04.116561Z","end":"2026-03-09T06:11:04.340367Z","steps":["trace[451870288] 'read index received' (duration: 219.417946ms)","trace[451870288] 'applied index is now lower than readState.Index' (duration: 4.386352ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:04.340523Z","caller":"traceutil/trace.go:171","msg":"trace[1629848049] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"262.257645ms","start":"2026-03-09T06:11:04.078207Z","end":"2026-03-09T06:11:04.340465Z","steps":["trace[1629848049] 'process raft request' (duration: 257.711919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:04.340656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.069147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-09T06:11:04.340729Z","caller":"traceutil/trace.go:171","msg":"trace[1211394757] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:320; }","duration":"224.219002ms","start":"2026-03-09T06:11:04.116479Z","end":"2026-03-09T06:11:04.340698Z","steps":["trace[1211394757] 'agreement among raft nodes before linearized reading' (duration: 224.029956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:05.07729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.005247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-09T06:11:05.077355Z","caller":"traceutil/trace.go:171","msg":"trace[465205195] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:334; }","duration":"181.12194ms","start":"2026-03-09T06:11:04.896219Z","end":"2026-03-09T06:11:05.077341Z","steps":["trace[465205195] 'range keys from in-memory index tree' (duration: 180.900223ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:06.550019Z","caller":"traceutil/trace.go:171","msg":"trace[711042708] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"158.69053ms","start":"2026-03-09T06:11:06.391303Z","end":"2026-03-09T06:11:06.549994Z","steps":["trace[711042708] 'process raft request' (duration: 158.578406ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:06.550029Z","caller":"traceutil/trace.go:171","msg":"trace[1315832504] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"158.70851ms","start":"2026-03-09T06:11:06.391271Z","end":"2026-03-09T06:11:06.54998Z","steps":["trace[1315832504] 'process raft request' (duration: 115.509876ms)","trace[1315832504] 'compare' (duration: 42.952515ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:06.55047Z","caller":"traceutil/trace.go:171","msg":"trace[1024619278] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"159.124103ms","start":"2026-03-09T06:11:06.391334Z","end":"2026-03-09T06:11:06.550458Z","steps":["trace[1024619278] 'process raft request' (duration: 158.611477ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:06.550565Z","caller":"traceutil/trace.go:171","msg":"trace[1119886263] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:380; }","duration":"158.858895ms","start":"2026-03-09T06:11:06.391691Z","end":"2026-03-09T06:11:06.55055Z","steps":["trace[1119886263] 'read index received' (duration: 55.695477ms)","trace[1119886263] 'applied index is now lower than readState.Index' (duration: 103.162258ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:06.550676Z","caller":"traceutil/trace.go:171","msg":"trace[1582647415] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"149.242625ms","start":"2026-03-09T06:11:06.401417Z","end":"2026-03-09T06:11:06.55066Z","steps":["trace[1582647415] 'process raft request' (duration: 148.902624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:06.550883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.68406ms","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-09T06:11:06.55093Z","caller":"traceutil/trace.go:171","msg":"trace[1225578127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:374; }","duration":"154.773933ms","start":"2026-03-09T06:11:06.396142Z","end":"2026-03-09T06:11:06.550916Z","steps":["trace[1225578127] 'agreement among raft nodes before linearized reading' (duration: 154.648719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:06.551045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.363241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-03-09T06:11:06.551078Z","caller":"traceutil/trace.go:171","msg":"trace[1501631908] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"159.425433ms","start":"2026-03-09T06:11:06.391644Z","end":"2026-03-09T06:11:06.551069Z","steps":["trace[1501631908] 'agreement among raft nodes before linearized reading' (duration: 159.362421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:06.551076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.856952ms","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-09T06:11:06.551104Z","caller":"traceutil/trace.go:171","msg":"trace[80262980] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"104.908734ms","start":"2026-03-09T06:11:06.446187Z","end":"2026-03-09T06:11:06.551095Z","steps":["trace[80262980] 'agreement among raft nodes before linearized reading' (duration: 104.856382ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:26.227812Z","caller":"traceutil/trace.go:171","msg":"trace[1457154559] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"129.073824ms","start":"2026-03-09T06:11:26.098719Z","end":"2026-03-09T06:11:26.227793Z","steps":["trace[1457154559] 'process raft request' (duration: 128.929739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:26.640514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.181186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:11:26.64069Z","caller":"traceutil/trace.go:171","msg":"trace[232974937] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"407.786409ms","start":"2026-03-09T06:11:26.232883Z","end":"2026-03-09T06:11:26.640669Z","steps":["trace[232974937] 'process raft request' (duration: 245.355435ms)","trace[232974937] 'compare' (duration: 162.034801ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:11:26.640846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:26.232866Z","time spent":"407.902943ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:11:26.64094Z","caller":"traceutil/trace.go:171","msg":"trace[1177500459] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"406.564429ms","start":"2026-03-09T06:11:26.234344Z","end":"2026-03-09T06:11:26.640909Z","steps":["trace[1177500459] 'process raft request' (duration: 406.275259ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:26.641015Z","caller":"traceutil/trace.go:171","msg":"trace[1773364850] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"405.351448ms","start":"2026-03-09T06:11:26.235642Z","end":"2026-03-09T06:11:26.640994Z","steps":["trace[1773364850] 'process raft request' (duration: 405.209684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:26.641097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:26.235597Z","time spent":"405.462172ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:26.641097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:26.234318Z","time spent":"406.696163ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:26.641106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.519496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-09T06:11:26.641272Z","caller":"traceutil/trace.go:171","msg":"trace[359787464] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:512; }","duration":"145.742883ms","start":"2026-03-09T06:11:26.495515Z","end":"2026-03-09T06:11:26.641258Z","steps":["trace[359787464] 'agreement among raft nodes before linearized reading' (duration: 145.532806ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:26.640993Z","caller":"traceutil/trace.go:171","msg":"trace[1283908109] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"145.395131ms","start":"2026-03-09T06:11:26.495567Z","end":"2026-03-09T06:11:26.640962Z","steps":["trace[1283908109] 'read index received' (duration: 103.756759ms)","trace[1283908109] 'applied index is now lower than readState.Index' (duration: 41.637472ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:26.877479Z","caller":"traceutil/trace.go:171","msg":"trace[485092956] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"227.622261ms","start":"2026-03-09T06:11:26.649834Z","end":"2026-03-09T06:11:26.877457Z","steps":["trace[485092956] 'process raft request' (duration: 227.478076ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:32.873714Z","caller":"traceutil/trace.go:171","msg":"trace[750263517] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"356.745765ms","start":"2026-03-09T06:11:32.516949Z","end":"2026-03-09T06:11:32.873695Z","steps":["trace[750263517] 'read index received' (duration: 356.613351ms)","trace[750263517] 'applied index is now lower than readState.Index' (duration: 131.884µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:32.873936Z","caller":"traceutil/trace.go:171","msg":"trace[787890264] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"617.245136ms","start":"2026-03-09T06:11:32.256684Z","end":"2026-03-09T06:11:32.873929Z","steps":["trace[787890264] 'process raft request' (duration: 616.927576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:32.873999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:32.256668Z","time spent":"617.287538ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:32.874173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.58444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T06:11:32.874192Z","caller":"traceutil/trace.go:171","msg":"trace[614607512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"204.611081ms","start":"2026-03-09T06:11:32.669575Z","end":"2026-03-09T06:11:32.874186Z","steps":["trace[614607512] 'agreement among raft nodes before linearized reading' (duration: 204.57387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:32.874258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.321425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:11:32.87427Z","caller":"traceutil/trace.go:171","msg":"trace[103270797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"357.363306ms","start":"2026-03-09T06:11:32.516902Z","end":"2026-03-09T06:11:32.874266Z","steps":["trace[103270797] 'agreement among raft nodes before linearized reading' (duration: 357.343045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:32.874213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.339431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T06:11:32.874341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.021574ms","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-03-09T06:11:32.874328Z","caller":"traceutil/trace.go:171","msg":"trace[1642254506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"207.515307ms","start":"2026-03-09T06:11:32.666788Z","end":"2026-03-09T06:11:32.874303Z","steps":["trace[1642254506] 'agreement among raft nodes before linearized reading' (duration: 207.258469ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:32.874356Z","caller":"traceutil/trace.go:171","msg":"trace[1199052802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"205.046015ms","start":"2026-03-09T06:11:32.669306Z","end":"2026-03-09T06:11:32.874352Z","steps":["trace[1199052802] 'agreement among raft nodes before linearized reading' (duration: 205.021024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:32.87428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:32.516885Z","time spent":"357.392556ms","remote":"127.0.0.1:41954","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-03-09T06:11:33.288836Z","caller":"traceutil/trace.go:171","msg":"trace[796599969] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"410.025768ms","start":"2026-03-09T06:11:32.87871Z","end":"2026-03-09T06:11:33.288735Z","steps":["trace[796599969] 'process raft request' (duration: 366.489643ms)","trace[796599969] 'compare' (duration: 43.256907ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:11:33.289372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:32.878683Z","time spent":"410.44411ms","remote":"127.0.0.1:42212","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-03-09T06:11:33.290858Z","caller":"traceutil/trace.go:171","msg":"trace[1725540436] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"411.672156ms","start":"2026-03-09T06:11:32.879122Z","end":"2026-03-09T06:11:33.290794Z","steps":["trace[1725540436] 'process raft request' (duration: 411.324446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:33.291006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:32.879103Z","time spent":"411.82614ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:11:33.291055Z","caller":"traceutil/trace.go:171","msg":"trace[781990551] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"411.487858ms","start":"2026-03-09T06:11:32.879539Z","end":"2026-03-09T06:11:33.291026Z","steps":["trace[781990551] 'process raft request' (duration: 411.061826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:33.291219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:32.879525Z","time spent":"411.609022ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:35.019155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255633826185388,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-09T06:11:35.236207Z","caller":"traceutil/trace.go:171","msg":"trace[113515370] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"718.083135ms","start":"2026-03-09T06:11:34.518095Z","end":"2026-03-09T06:11:35.236178Z","steps":["trace[113515370] 'read index received' (duration: 717.751654ms)","trace[113515370] 'applied index is now lower than readState.Index' (duration: 330.221µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:35.236309Z","caller":"traceutil/trace.go:171","msg":"trace[448529635] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"897.953411ms","start":"2026-03-09T06:11:34.338318Z","end":"2026-03-09T06:11:35.236272Z","steps":["trace[448529635] 'process raft request' (duration: 897.62319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:35.23642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.306105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:11:35.236446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.25086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-09T06:11:35.236503Z","caller":"traceutil/trace.go:171","msg":"trace[177222525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"718.441275ms","start":"2026-03-09T06:11:34.51804Z","end":"2026-03-09T06:11:35.236481Z","steps":["trace[177222525] 'agreement among raft nodes before linearized reading' (duration: 718.276895ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:35.236512Z","caller":"traceutil/trace.go:171","msg":"trace[144093977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"357.35909ms","start":"2026-03-09T06:11:34.879126Z","end":"2026-03-09T06:11:35.236485Z","steps":["trace[144093977] 'agreement among raft nodes before linearized reading' (duration: 357.19183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:35.236545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:34.51802Z","time spent":"718.518585ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:11:35.236559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:34.879101Z","time spent":"357.44552ms","remote":"127.0.0.1:42212","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-09T06:11:35.236525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:34.338291Z","time spent":"898.089771ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:35.79486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255633826185391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:11:36.153495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.362671ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:11:36.153618Z","caller":"traceutil/trace.go:171","msg":"trace[834490916] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:554; }","duration":"753.499071ms","start":"2026-03-09T06:11:35.400102Z","end":"2026-03-09T06:11:36.153601Z","steps":["trace[834490916] 'range keys from in-memory index tree' (duration: 753.302381ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:36.15383Z","caller":"traceutil/trace.go:171","msg":"trace[2102794034] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"912.464882ms","start":"2026-03-09T06:11:35.241345Z","end":"2026-03-09T06:11:36.15381Z","steps":["trace[2102794034] 'process raft request' (duration: 860.75346ms)","trace[2102794034] 'compare' (duration: 51.104401ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:11:36.153844Z","caller":"traceutil/trace.go:171","msg":"trace[1495865111] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"859.938002ms","start":"2026-03-09T06:11:35.293869Z","end":"2026-03-09T06:11:36.153807Z","steps":["trace[1495865111] 'read index received' (duration: 808.28196ms)","trace[1495865111] 'applied index is now lower than readState.Index' (duration: 51.654442ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:11:36.154092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:35.241328Z","time spent":"912.631332ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:11:36.154254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.96218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-09T06:11:36.154252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.482831ms","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-09T06:11:36.154311Z","caller":"traceutil/trace.go:171","msg":"trace[538676137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"859.0253ms","start":"2026-03-09T06:11:35.295268Z","end":"2026-03-09T06:11:36.154294Z","steps":["trace[538676137] 'agreement among raft nodes before linearized reading' (duration: 858.92728ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:36.154327Z","caller":"traceutil/trace.go:171","msg":"trace[469675151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"859.603441ms","start":"2026-03-09T06:11:35.294703Z","end":"2026-03-09T06:11:36.154306Z","steps":["trace[469675151] 'agreement among raft nodes before linearized reading' (duration: 859.357161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:36.154357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:35.295263Z","time spent":"859.08632ms","remote":"127.0.0.1:42212","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-09T06:11:36.154379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:35.294688Z","time spent":"859.677001ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T06:11:36.15414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.259782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T06:11:36.154475Z","caller":"traceutil/trace.go:171","msg":"trace[1877367654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"860.668482ms","start":"2026-03-09T06:11:35.293798Z","end":"2026-03-09T06:11:36.154467Z","steps":["trace[1877367654] 'agreement among raft nodes before linearized reading' (duration: 860.161682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:36.154508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:35.293777Z","time spent":"860.724702ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T06:11:36.154202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.1173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:11:36.154577Z","caller":"traceutil/trace.go:171","msg":"trace[1964431787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"637.53749ms","start":"2026-03-09T06:11:35.517031Z","end":"2026-03-09T06:11:36.154569Z","steps":["trace[1964431787] 'agreement among raft nodes before linearized reading' (duration: 637.13036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:36.154608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:35.517013Z","time spent":"637.58894ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:11:37.783209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.980261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-09T06:11:37.783205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.78258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:11:37.783276Z","caller":"traceutil/trace.go:171","msg":"trace[1598939663] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:578; }","duration":"282.094661ms","start":"2026-03-09T06:11:37.501163Z","end":"2026-03-09T06:11:37.783258Z","steps":["trace[1598939663] 'range keys from in-memory index tree' (duration: 281.806491ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:37.783285Z","caller":"traceutil/trace.go:171","msg":"trace[2024266253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"264.90187ms","start":"2026-03-09T06:11:37.518376Z","end":"2026-03-09T06:11:37.783278Z","steps":["trace[2024266253] 'range keys from in-memory index tree' (duration: 264.720559ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:11:37.949486Z","caller":"traceutil/trace.go:171","msg":"trace[1605824206] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"313.63244ms","start":"2026-03-09T06:11:37.63583Z","end":"2026-03-09T06:11:37.949462Z","steps":["trace[1605824206] 'process raft request' (duration: 313.51008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:11:37.949624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:11:37.635799Z","time spent":"313.76393ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T06:11:37.954725Z","caller":"traceutil/trace.go:171","msg":"trace[933683664] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"120.077692ms","start":"2026-03-09T06:11:37.834592Z","end":"2026-03-09T06:11:37.95467Z","steps":["trace[933683664] 'process raft request' (duration: 119.944272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:13:07.699182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.676843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:13:07.699332Z","caller":"traceutil/trace.go:171","msg":"trace[559504603] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"147.527864ms","start":"2026-03-09T06:13:07.551781Z","end":"2026-03-09T06:13:07.699308Z","steps":["trace[559504603] 'process raft request' (duration: 32.652078ms)","trace[559504603] 'compare' (duration: 114.556599ms)"],"step_count":2}