{"level":"warn","ts":"2026-09-18T05:15:34.32207Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:15:34.322161Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.126: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.126:2380","--initial-cluster=instance=https://162.253.55.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.126: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-09-18T05:15:34.322221Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:15:34.322234Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-09-18T05:15:34.322258Z","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-09-18T05:15:34.322969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"]} {"level":"info","ts":"2026-09-18T05:15:34.323045Z","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.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.126: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-09-18T05:15:34.326697Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.389408ms"} {"level":"info","ts":"2026-09-18T05:15:34.331485Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"85ebf4ab7bdf9269","cluster-id":"f923bca632e7887a"} {"level":"info","ts":"2026-09-18T05:15:34.331539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:15:34.331557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:15:34.331571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 85ebf4ab7bdf9269 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:15:34.331579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:15:34.331613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"warn","ts":"2026-09-18T05:15:34.335995Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:15:34.337167Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:15:34.33822Z","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-09-18T05:15:34.340079Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"85ebf4ab7bdf9269","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:15:34.340215Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"85ebf4ab7bdf9269","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:15:34.340335Z","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-09-18T05:15:34.34042Z","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-09-18T05:15:34.340427Z","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-09-18T05:15:34.34547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"info","ts":"2026-09-18T05:15:34.345952Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","added-peer-id":"85ebf4ab7bdf9269","added-peer-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-09-18T05:15:34.346921Z","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-09-18T05:15:34.347054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-09-18T05:15:34.347113Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"85ebf4ab7bdf9269","initial-advertise-peer-urls":["https://162.253.55.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:15:34.34712Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-09-18T05:15:34.347163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:15:34.73243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:15:34.732538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:15:34.732587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgPreVoteResp from 85ebf4ab7bdf9269 at term 1"} {"level":"info","ts":"2026-09-18T05:15:34.732639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:15:34.732677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgVoteResp from 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-09-18T05:15:34.732746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:15:34.732797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 85ebf4ab7bdf9269 elected leader 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-09-18T05:15:34.733891Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"85ebf4ab7bdf9269","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.126:2379]}","request-path":"/0/members/85ebf4ab7bdf9269/attributes","cluster-id":"f923bca632e7887a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:15:34.734161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:15:34.735065Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:15:34.735518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:15:34.7358Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:15:34.735843Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:15:34.735892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:15:34.735996Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:15:34.736031Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:15:34.738346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.126:2379"} {"level":"info","ts":"2026-09-18T05:15:34.739855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:15:36.221615Z","caller":"traceutil/trace.go:171","msg":"trace[449241625] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"112.566204ms","start":"2026-09-18T05:15:36.10902Z","end":"2026-09-18T05:15:36.221586Z","steps":["trace[449241625] 'process raft request' (duration: 112.302648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.221828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.470312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.221892Z","caller":"traceutil/trace.go:171","msg":"trace[2031198721] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"109.563684ms","start":"2026-09-18T05:15:36.112316Z","end":"2026-09-18T05:15:36.221879Z","steps":["trace[2031198721] 'agreement among raft nodes before linearized reading' (duration: 109.349579ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.221602Z","caller":"traceutil/trace.go:171","msg":"trace[585213397] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"109.224967ms","start":"2026-09-18T05:15:36.112331Z","end":"2026-09-18T05:15:36.221556Z","steps":["trace[585213397] 'read index received' (duration: 109.059273ms)","trace[585213397] 'applied index is now lower than readState.Index' (duration: 164.714µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:36.490941Z","caller":"traceutil/trace.go:171","msg":"trace[1321705434] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"268.679657ms","start":"2026-09-18T05:15:36.222236Z","end":"2026-09-18T05:15:36.490916Z","steps":["trace[1321705434] 'read index received' (duration: 268.145795ms)","trace[1321705434] 'applied index is now lower than readState.Index' (duration: 532.862µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:36.491354Z","caller":"traceutil/trace.go:171","msg":"trace[1397402167] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"293.824774ms","start":"2026-09-18T05:15:36.197467Z","end":"2026-09-18T05:15:36.491292Z","steps":["trace[1397402167] 'process raft request' (duration: 293.310522ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.49138Z","caller":"traceutil/trace.go:171","msg":"trace[804931408] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"293.799673ms","start":"2026-09-18T05:15:36.197545Z","end":"2026-09-18T05:15:36.491345Z","steps":["trace[804931408] 'process raft request' (duration: 293.261961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.491565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.88107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:15:36.491629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.703635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-09-18T05:15:36.491696Z","caller":"traceutil/trace.go:171","msg":"trace[1995045540] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"288.167374ms","start":"2026-09-18T05:15:36.203478Z","end":"2026-09-18T05:15:36.491645Z","steps":["trace[1995045540] 'process raft request' (duration: 287.400796ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.491787Z","caller":"traceutil/trace.go:171","msg":"trace[430180788] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"376.90372ms","start":"2026-09-18T05:15:36.114843Z","end":"2026-09-18T05:15:36.491747Z","steps":["trace[430180788] 'agreement among raft nodes before linearized reading' (duration: 376.577293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.491835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.120485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:15:36.491855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.114834Z","time spent":"377.006432ms","remote":"127.0.0.1:37662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3195,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-18T05:15:36.491766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.960512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.491937Z","caller":"traceutil/trace.go:171","msg":"trace[2024019842] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"323.360951ms","start":"2026-09-18T05:15:36.168565Z","end":"2026-09-18T05:15:36.491926Z","steps":["trace[2024019842] 'agreement among raft nodes before linearized reading' (duration: 322.712096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.49198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.168544Z","time spent":"323.425153ms","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T05:15:36.492001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.609322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.126\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.491898Z","caller":"traceutil/trace.go:171","msg":"trace[547837442] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"326.224127ms","start":"2026-09-18T05:15:36.16565Z","end":"2026-09-18T05:15:36.491874Z","steps":["trace[547837442] 'agreement among raft nodes before linearized reading' (duration: 326.133905ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.492043Z","caller":"traceutil/trace.go:171","msg":"trace[1312978839] range","detail":"{range_begin:/registry/masterleases/162.253.55.126; range_end:; response_count:0; response_revision:10; }","duration":"301.658913ms","start":"2026-09-18T05:15:36.190367Z","end":"2026-09-18T05:15:36.492026Z","steps":["trace[1312978839] 'agreement among raft nodes before linearized reading' (duration: 301.590382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.492089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.165635Z","time spent":"326.443362ms","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T05:15:36.491546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.957471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:15:36.492126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.190318Z","time spent":"301.797486ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.126\" "} {"level":"info","ts":"2026-09-18T05:15:36.491682Z","caller":"traceutil/trace.go:171","msg":"trace[2118041591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"323.157307ms","start":"2026-09-18T05:15:36.168508Z","end":"2026-09-18T05:15:36.491665Z","steps":["trace[2118041591] 'agreement among raft nodes before linearized reading' (duration: 322.82681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.49225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.168496Z","time spent":"323.74399ms","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T05:15:36.492197Z","caller":"traceutil/trace.go:171","msg":"trace[2059470789] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"292.726948ms","start":"2026-09-18T05:15:36.199453Z","end":"2026-09-18T05:15:36.49218Z","steps":["trace[2059470789] 'agreement among raft nodes before linearized reading' (duration: 291.913599ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.491753Z","caller":"traceutil/trace.go:171","msg":"trace[837856777] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"291.193274ms","start":"2026-09-18T05:15:36.200547Z","end":"2026-09-18T05:15:36.49174Z","steps":["trace[837856777] 'process raft request' (duration: 290.299804ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.491597Z","caller":"traceutil/trace.go:171","msg":"trace[1404711303] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"293.745912ms","start":"2026-09-18T05:15:36.197839Z","end":"2026-09-18T05:15:36.491584Z","steps":["trace[1404711303] 'process raft request' (duration: 292.989405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.491937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.504417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.492607Z","caller":"traceutil/trace.go:171","msg":"trace[798137865] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"301.190673ms","start":"2026-09-18T05:15:36.191403Z","end":"2026-09-18T05:15:36.492594Z","steps":["trace[798137865] 'agreement among raft nodes before linearized reading' (duration: 300.505307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.492787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.191393Z","time spent":"301.300476ms","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T05:15:36.49277Z","caller":"traceutil/trace.go:171","msg":"trace[858471455] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"300.188801ms","start":"2026-09-18T05:15:36.19256Z","end":"2026-09-18T05:15:36.492749Z","steps":["trace[858471455] 'process raft request' (duration: 297.99073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.492784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.190046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.495026Z","caller":"traceutil/trace.go:171","msg":"trace[976079293] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"382.460097ms","start":"2026-09-18T05:15:36.112549Z","end":"2026-09-18T05:15:36.495009Z","steps":["trace[976079293] 'agreement among raft nodes before linearized reading' (duration: 379.155602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.495111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.112528Z","time spent":"382.543249ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-09-18T05:15:36.493022Z","caller":"traceutil/trace.go:171","msg":"trace[2005384393] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"296.612828ms","start":"2026-09-18T05:15:36.196379Z","end":"2026-09-18T05:15:36.492992Z","steps":["trace[2005384393] 'process raft request' (duration: 294.366196ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:36.493067Z","caller":"traceutil/trace.go:171","msg":"trace[461128425] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"297.425276ms","start":"2026-09-18T05:15:36.195632Z","end":"2026-09-18T05:15:36.493057Z","steps":["trace[461128425] 'process raft request' (duration: 295.060192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.494833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.192535Z","time spent":"300.303124ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:15:36.525837Z","caller":"traceutil/trace.go:171","msg":"trace[145418934] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"302.204275ms","start":"2026-09-18T05:15:36.223608Z","end":"2026-09-18T05:15:36.525812Z","steps":["trace[145418934] 'process raft request' (duration: 302.065622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.526005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.580402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:15:36.526101Z","caller":"traceutil/trace.go:171","msg":"trace[1211935346] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"281.754077ms","start":"2026-09-18T05:15:36.244331Z","end":"2026-09-18T05:15:36.526085Z","steps":["trace[1211935346] 'agreement among raft nodes before linearized reading' (duration: 281.51538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:36.526194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:15:36.223593Z","time spent":"302.331068ms","remote":"127.0.0.1:37528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:16:14.520573Z","caller":"traceutil/trace.go:171","msg":"trace[212040369] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"205.109587ms","start":"2026-09-18T05:16:14.31544Z","end":"2026-09-18T05:16:14.52055Z","steps":["trace[212040369] 'process raft request' (duration: 204.989025ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:20.312893Z","caller":"traceutil/trace.go:171","msg":"trace[1182346993] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"235.042159ms","start":"2026-09-18T05:16:20.077822Z","end":"2026-09-18T05:16:20.312864Z","steps":["trace[1182346993] 'process raft request' (duration: 229.029422ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:20.314112Z","caller":"traceutil/trace.go:171","msg":"trace[809452354] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"198.225496ms","start":"2026-09-18T05:16:20.115869Z","end":"2026-09-18T05:16:20.314094Z","steps":["trace[809452354] 'process raft request' (duration: 197.705654ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:20.31551Z","caller":"traceutil/trace.go:171","msg":"trace[1137176511] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"138.235363ms","start":"2026-09-18T05:16:20.177156Z","end":"2026-09-18T05:16:20.315392Z","steps":["trace[1137176511] 'process raft request' (duration: 136.640536ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:20.315636Z","caller":"traceutil/trace.go:171","msg":"trace[399124644] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"123.859834ms","start":"2026-09-18T05:16:20.191741Z","end":"2026-09-18T05:16:20.315601Z","steps":["trace[399124644] 'read index received' (duration: 115.021362ms)","trace[399124644] 'applied index is now lower than readState.Index' (duration: 8.836522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:20.315898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.747722ms","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-09-18T05:16:20.315962Z","caller":"traceutil/trace.go:171","msg":"trace[887616009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"109.843624ms","start":"2026-09-18T05:16:20.2061Z","end":"2026-09-18T05:16:20.315944Z","steps":["trace[887616009] 'agreement among raft nodes before linearized reading' (duration: 109.722761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:20.31597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.220123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:20.316026Z","caller":"traceutil/trace.go:171","msg":"trace[1442789146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"124.316684ms","start":"2026-09-18T05:16:20.191694Z","end":"2026-09-18T05:16:20.316011Z","steps":["trace[1442789146] 'agreement among raft nodes before linearized reading' (duration: 124.230022ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:26.520936Z","caller":"traceutil/trace.go:171","msg":"trace[1919028104] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"169.908159ms","start":"2026-09-18T05:16:26.350981Z","end":"2026-09-18T05:16:26.520889Z","steps":["trace[1919028104] 'process raft request' (duration: 169.740455ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:26.688448Z","caller":"traceutil/trace.go:171","msg":"trace[780570386] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"101.507793ms","start":"2026-09-18T05:16:26.586917Z","end":"2026-09-18T05:16:26.688425Z","steps":["trace[780570386] 'process raft request' (duration: 101.36442ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:27.026078Z","caller":"traceutil/trace.go:171","msg":"trace[1424734627] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"150.248088ms","start":"2026-09-18T05:16:26.875803Z","end":"2026-09-18T05:16:27.026051Z","steps":["trace[1424734627] 'process raft request' (duration: 150.070704ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:27.64227Z","caller":"traceutil/trace.go:171","msg":"trace[1639655713] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"289.901034ms","start":"2026-09-18T05:16:27.352342Z","end":"2026-09-18T05:16:27.642243Z","steps":["trace[1639655713] 'read index received' (duration: 289.763471ms)","trace[1639655713] 'applied index is now lower than readState.Index' (duration: 136.423µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:16:27.64234Z","caller":"traceutil/trace.go:171","msg":"trace[781542721] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"402.497641ms","start":"2026-09-18T05:16:27.239817Z","end":"2026-09-18T05:16:27.642315Z","steps":["trace[781542721] 'process raft request' (duration: 402.269336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.642552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.132539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-18T05:16:27.642566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:27.239799Z","time spent":"402.611513ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:16:27.642719Z","caller":"traceutil/trace.go:171","msg":"trace[1332168100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"290.391696ms","start":"2026-09-18T05:16:27.352311Z","end":"2026-09-18T05:16:27.642703Z","steps":["trace[1332168100] 'agreement among raft nodes before linearized reading' (duration: 290.049887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:27.701226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.249116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T05:16:27.701291Z","caller":"traceutil/trace.go:171","msg":"trace[1932836798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"267.341407ms","start":"2026-09-18T05:16:27.433933Z","end":"2026-09-18T05:16:27.701274Z","steps":["trace[1932836798] 'agreement among raft nodes before linearized reading' (duration: 267.101852ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:27.701331Z","caller":"traceutil/trace.go:171","msg":"trace[1280443482] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"261.547215ms","start":"2026-09-18T05:16:27.439759Z","end":"2026-09-18T05:16:27.701306Z","steps":["trace[1280443482] 'process raft request' (duration: 261.160086ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:27.773978Z","caller":"traceutil/trace.go:171","msg":"trace[109185328] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"128.153252ms","start":"2026-09-18T05:16:27.645801Z","end":"2026-09-18T05:16:27.773954Z","steps":["trace[109185328] 'process raft request' (duration: 75.402135ms)","trace[109185328] 'compare' (duration: 52.42805ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:16:44.283117Z","caller":"traceutil/trace.go:171","msg":"trace[1497007895] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"384.822257ms","start":"2026-09-18T05:16:43.898233Z","end":"2026-09-18T05:16:44.283055Z","steps":["trace[1497007895] 'process raft request' (duration: 384.584822ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:44.28305Z","caller":"traceutil/trace.go:171","msg":"trace[613514059] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"253.620214ms","start":"2026-09-18T05:16:44.029399Z","end":"2026-09-18T05:16:44.283019Z","steps":["trace[613514059] 'read index received' (duration: 253.324797ms)","trace[613514059] 'applied index is now lower than readState.Index' (duration: 293.987µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:16:44.283303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:16:43.898213Z","time spent":"385.000922ms","remote":"127.0.0.1:37670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:16:44.283375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.85701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:16:44.283403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.581572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.283451Z","caller":"traceutil/trace.go:171","msg":"trace[1773501838] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"138.650403ms","start":"2026-09-18T05:16:44.144792Z","end":"2026-09-18T05:16:44.283443Z","steps":["trace[1773501838] 'agreement among raft nodes before linearized reading' (duration: 138.574571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:44.283364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.902251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:16:44.283519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.730004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.283558Z","caller":"traceutil/trace.go:171","msg":"trace[114710613] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:787; }","duration":"162.811035ms","start":"2026-09-18T05:16:44.120736Z","end":"2026-09-18T05:16:44.283547Z","steps":["trace[114710613] 'agreement among raft nodes before linearized reading' (duration: 162.733893ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:44.283551Z","caller":"traceutil/trace.go:171","msg":"trace[1074654310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:787; }","duration":"254.158967ms","start":"2026-09-18T05:16:44.02937Z","end":"2026-09-18T05:16:44.283529Z","steps":["trace[1074654310] 'agreement among raft nodes before linearized reading' (duration: 253.889411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:44.283789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.528431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.283833Z","caller":"traceutil/trace.go:171","msg":"trace[1481496243] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"138.581601ms","start":"2026-09-18T05:16:44.145242Z","end":"2026-09-18T05:16:44.283824Z","steps":["trace[1481496243] 'agreement among raft nodes before linearized reading' (duration: 138.52779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:44.283934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.083703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.283967Z","caller":"traceutil/trace.go:171","msg":"trace[1313213494] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"139.119194ms","start":"2026-09-18T05:16:44.144843Z","end":"2026-09-18T05:16:44.283962Z","steps":["trace[1313213494] 'agreement among raft nodes before linearized reading' (duration: 139.072773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:44.283959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.640682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.284147Z","caller":"traceutil/trace.go:171","msg":"trace[359191135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:787; }","duration":"162.878387ms","start":"2026-09-18T05:16:44.121185Z","end":"2026-09-18T05:16:44.284063Z","steps":["trace[359191135] 'agreement among raft nodes before linearized reading' (duration: 162.625851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:16:44.28432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.701766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:16:44.283425Z","caller":"traceutil/trace.go:171","msg":"trace[715028778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:787; }","duration":"253.973372ms","start":"2026-09-18T05:16:44.029439Z","end":"2026-09-18T05:16:44.283413Z","steps":["trace[715028778] 'agreement among raft nodes before linearized reading' (duration: 253.844989ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:16:44.284381Z","caller":"traceutil/trace.go:171","msg":"trace[1828478105] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:787; }","duration":"163.797677ms","start":"2026-09-18T05:16:44.120559Z","end":"2026-09-18T05:16:44.284357Z","steps":["trace[1828478105] 'agreement among raft nodes before linearized reading' (duration: 163.705565ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:26.275678Z","caller":"traceutil/trace.go:171","msg":"trace[543690441] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"220.973428ms","start":"2026-09-18T05:17:26.05468Z","end":"2026-09-18T05:17:26.275654Z","steps":["trace[543690441] 'read index received' (duration: 220.6792ms)","trace[543690441] 'applied index is now lower than readState.Index' (duration: 292.828µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:26.275752Z","caller":"traceutil/trace.go:171","msg":"trace[519974575] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"319.592219ms","start":"2026-09-18T05:17:25.956124Z","end":"2026-09-18T05:17:26.275716Z","steps":["trace[519974575] 'process raft request' (duration: 319.323543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:26.275904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:25.956102Z","time spent":"319.722703ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:17:26.275962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.265144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-18T05:17:26.276008Z","caller":"traceutil/trace.go:171","msg":"trace[1896181829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"221.367276ms","start":"2026-09-18T05:17:26.054626Z","end":"2026-09-18T05:17:26.275993Z","steps":["trace[1896181829] 'agreement among raft nodes before linearized reading' (duration: 221.200883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:26.276226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.813715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-18T05:17:26.276349Z","caller":"traceutil/trace.go:171","msg":"trace[739807398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"192.982108ms","start":"2026-09-18T05:17:26.083343Z","end":"2026-09-18T05:17:26.276325Z","steps":["trace[739807398] 'agreement among raft nodes before linearized reading' (duration: 192.678851ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:42.293431Z","caller":"traceutil/trace.go:171","msg":"trace[1096287872] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"102.857608ms","start":"2026-09-18T05:17:42.190536Z","end":"2026-09-18T05:17:42.293394Z","steps":["trace[1096287872] 'read index received' (duration: 102.321076ms)","trace[1096287872] 'applied index is now lower than readState.Index' (duration: 535.002µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:42.293529Z","caller":"traceutil/trace.go:171","msg":"trace[116711039] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"175.483757ms","start":"2026-09-18T05:17:42.118007Z","end":"2026-09-18T05:17:42.293491Z","steps":["trace[116711039] 'process raft request' (duration: 174.947895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:42.293775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.204818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:17:42.294304Z","caller":"traceutil/trace.go:171","msg":"trace[592074892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"103.35935ms","start":"2026-09-18T05:17:42.190494Z","end":"2026-09-18T05:17:42.293853Z","steps":["trace[592074892] 'agreement among raft nodes before linearized reading' (duration: 103.116124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:42.69382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.035699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-18T05:17:42.69384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.002901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T05:17:42.693891Z","caller":"traceutil/trace.go:171","msg":"trace[1376537191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"267.177482ms","start":"2026-09-18T05:17:42.426698Z","end":"2026-09-18T05:17:42.693875Z","steps":["trace[1376537191] 'range keys from in-memory index tree' (duration: 266.692631ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:42.693909Z","caller":"traceutil/trace.go:171","msg":"trace[1293330547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1144; }","duration":"261.118864ms","start":"2026-09-18T05:17:42.432772Z","end":"2026-09-18T05:17:42.693891Z","steps":["trace[1293330547] 'range keys from in-memory index tree' (duration: 260.861598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:42.693905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.481679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T05:17:42.693941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.394417ms","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-09-18T05:17:42.694006Z","caller":"traceutil/trace.go:171","msg":"trace[694329863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1144; }","duration":"330.498449ms","start":"2026-09-18T05:17:42.363488Z","end":"2026-09-18T05:17:42.693986Z","steps":["trace[694329863] 'range keys from in-memory index tree' (duration: 330.047328ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:42.694006Z","caller":"traceutil/trace.go:171","msg":"trace[917803397] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1144; }","duration":"281.648963ms","start":"2026-09-18T05:17:42.412333Z","end":"2026-09-18T05:17:42.693982Z","steps":["trace[917803397] 'count revisions from in-memory index tree' (duration: 281.369396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:42.694047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:42.363464Z","time spent":"330.572521ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T05:17:42.823429Z","caller":"traceutil/trace.go:171","msg":"trace[761718170] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"124.695679ms","start":"2026-09-18T05:17:42.698706Z","end":"2026-09-18T05:17:42.823402Z","steps":["trace[761718170] 'process raft request' (duration: 124.485054ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:42.889099Z","caller":"traceutil/trace.go:171","msg":"trace[762189988] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"189.278484ms","start":"2026-09-18T05:17:42.699785Z","end":"2026-09-18T05:17:42.889063Z","steps":["trace[762189988] 'process raft request' (duration: 189.226303ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:42.889622Z","caller":"traceutil/trace.go:171","msg":"trace[1308058516] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"189.830186ms","start":"2026-09-18T05:17:42.699726Z","end":"2026-09-18T05:17:42.889556Z","steps":["trace[1308058516] 'process raft request' (duration: 189.11712ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:43.527228Z","caller":"traceutil/trace.go:171","msg":"trace[1281275459] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"223.168177ms","start":"2026-09-18T05:17:43.304034Z","end":"2026-09-18T05:17:43.527202Z","steps":["trace[1281275459] 'process raft request' (duration: 144.695385ms)","trace[1281275459] 'compare' (duration: 78.328299ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:05.116223Z","caller":"traceutil/trace.go:171","msg":"trace[2120319574] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"103.198657ms","start":"2026-09-18T05:18:05.013004Z","end":"2026-09-18T05:18:05.116202Z","steps":["trace[2120319574] 'read index received' (duration: 102.990592ms)","trace[2120319574] 'applied index is now lower than readState.Index' (duration: 207.105µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:05.11636Z","caller":"traceutil/trace.go:171","msg":"trace[577774859] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"151.288846ms","start":"2026-09-18T05:18:04.965041Z","end":"2026-09-18T05:18:05.116329Z","steps":["trace[577774859] 'process raft request' (duration: 151.03165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:05.11659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.538305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-18T05:18:05.116671Z","caller":"traceutil/trace.go:171","msg":"trace[1494970231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"103.690289ms","start":"2026-09-18T05:18:05.012963Z","end":"2026-09-18T05:18:05.116653Z","steps":["trace[1494970231] 'agreement among raft nodes before linearized reading' (duration: 103.382081ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:05.309826Z","caller":"traceutil/trace.go:171","msg":"trace[656102211] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"188.824203ms","start":"2026-09-18T05:18:05.120972Z","end":"2026-09-18T05:18:05.309797Z","steps":["trace[656102211] 'process raft request' (duration: 117.368351ms)","trace[656102211] 'compare' (duration: 71.343559ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:05.320873Z","caller":"traceutil/trace.go:171","msg":"trace[574874391] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1327; }","duration":"128.929285ms","start":"2026-09-18T05:18:05.191923Z","end":"2026-09-18T05:18:05.320852Z","steps":["trace[574874391] 'read index received' (duration: 46.428691ms)","trace[574874391] 'applied index is now lower than readState.Index' (duration: 82.499724ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:05.320888Z","caller":"traceutil/trace.go:171","msg":"trace[1042367570] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"199.747482ms","start":"2026-09-18T05:18:05.12112Z","end":"2026-09-18T05:18:05.320868Z","steps":["trace[1042367570] 'process raft request' (duration: 199.592509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:05.321048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.10223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:18:05.32111Z","caller":"traceutil/trace.go:171","msg":"trace[1259759989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"129.221972ms","start":"2026-09-18T05:18:05.191872Z","end":"2026-09-18T05:18:05.321094Z","steps":["trace[1259759989] 'agreement among raft nodes before linearized reading' (duration: 129.094319ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:05.321243Z","caller":"traceutil/trace.go:171","msg":"trace[1023142592] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"199.423346ms","start":"2026-09-18T05:18:05.12179Z","end":"2026-09-18T05:18:05.321213Z","steps":["trace[1023142592] 'process raft request' (duration: 199.027026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:05.321685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.330955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T05:18:05.321754Z","caller":"traceutil/trace.go:171","msg":"trace[2099748037] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1280; }","duration":"126.433238ms","start":"2026-09-18T05:18:05.195303Z","end":"2026-09-18T05:18:05.321736Z","steps":["trace[2099748037] 'agreement among raft nodes before linearized reading' (duration: 125.745802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:05.922677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.004335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-18T05:18:05.922763Z","caller":"traceutil/trace.go:171","msg":"trace[1595672398] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1283; }","duration":"340.132018ms","start":"2026-09-18T05:18:05.582614Z","end":"2026-09-18T05:18:05.922746Z","steps":["trace[1595672398] 'range keys from in-memory index tree' (duration: 339.813371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:05.922799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:05.582595Z","time spent":"340.195449ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-09-18T05:18:06.120527Z","caller":"traceutil/trace.go:171","msg":"trace[1474572688] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"192.984707ms","start":"2026-09-18T05:18:05.92725Z","end":"2026-09-18T05:18:06.120234Z","steps":["trace[1474572688] 'process raft request' (duration: 192.813944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:36.66023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.692852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:22:36.660415Z","caller":"traceutil/trace.go:171","msg":"trace[1482492546] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"153.155617ms","start":"2026-09-18T05:22:36.507237Z","end":"2026-09-18T05:22:36.660392Z","steps":["trace[1482492546] 'process raft request' (duration: 45.918783ms)","trace[1482492546] 'compare' (duration: 106.531798ms)"],"step_count":2}