{"level":"warn","ts":"2026-09-19T01:02:30.371633Z","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-19T01:02:30.371797Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.112:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.112:2380","--initial-cluster=instance=https://162.253.55.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.112:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T01:02:30.371922Z","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-19T01:02:30.37194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-19T01:02:30.371975Z","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-19T01:02:30.373457Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"]} {"level":"info","ts":"2026-09-19T01:02:30.37369Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.112:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T01:02:30.380155Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.053177ms"} {"level":"info","ts":"2026-09-19T01:02:30.395248Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1b80133015df7bbe","cluster-id":"96c35284d1622e20"} {"level":"info","ts":"2026-09-19T01:02:30.395344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:02:30.395367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 0"} {"level":"info","ts":"2026-09-19T01:02:30.395378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1b80133015df7bbe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:02:30.395386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 1"} {"level":"info","ts":"2026-09-19T01:02:30.395424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"warn","ts":"2026-09-19T01:02:30.404415Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:02:30.405845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:02:30.407273Z","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-19T01:02:30.409694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1b80133015df7bbe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:02:30.411305Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1b80133015df7bbe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:02:30.41134Z","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-19T01:02:30.4147Z","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-19T01:02:30.414713Z","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-19T01:02:30.418978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"info","ts":"2026-09-19T01:02:30.41913Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","added-peer-id":"1b80133015df7bbe","added-peer-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-19T01:02:30.419949Z","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-19T01:02:30.420009Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-19T01:02:30.420063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-19T01:02:30.420119Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1b80133015df7bbe","initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:02:30.420153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:02:30.69664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:02:30.696691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:02:30.696716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgPreVoteResp from 1b80133015df7bbe at term 1"} {"level":"info","ts":"2026-09-19T01:02:30.696731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:02:30.696737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgVoteResp from 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-19T01:02:30.696747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became leader at term 2"} {"level":"info","ts":"2026-09-19T01:02:30.696756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1b80133015df7bbe elected leader 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-19T01:02:30.698592Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1b80133015df7bbe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.112:2379]}","request-path":"/0/members/1b80133015df7bbe/attributes","cluster-id":"96c35284d1622e20","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:02:30.698639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:02:30.698719Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:02:30.698983Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:02:30.699496Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:02:30.699519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:02:30.700042Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:02:30.700173Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:02:30.700274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:02:30.700783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T01:02:30.702005Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.112:2379"} {"level":"warn","ts":"2026-09-19T01:02:49.291604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.805832ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T01:03:13.158315Z","caller":"traceutil/trace.go:171","msg":"trace[2147104620] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"182.436774ms","start":"2026-09-19T01:03:12.97584Z","end":"2026-09-19T01:03:13.158277Z","steps":["trace[2147104620] 'process raft request' (duration: 182.343302ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.176603Z","caller":"traceutil/trace.go:171","msg":"trace[571724] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"198.031703ms","start":"2026-09-19T01:03:12.97855Z","end":"2026-09-19T01:03:13.176582Z","steps":["trace[571724] 'process raft request' (duration: 197.823168ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.176686Z","caller":"traceutil/trace.go:171","msg":"trace[431742395] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"191.904914ms","start":"2026-09-19T01:03:12.984746Z","end":"2026-09-19T01:03:13.176651Z","steps":["trace[431742395] 'process raft request' (duration: 191.76272ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.176695Z","caller":"traceutil/trace.go:171","msg":"trace[1483417950] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"191.693758ms","start":"2026-09-19T01:03:12.984952Z","end":"2026-09-19T01:03:13.176646Z","steps":["trace[1483417950] 'process raft request' (duration: 191.614846ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.177142Z","caller":"traceutil/trace.go:171","msg":"trace[534063207] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"178.869735ms","start":"2026-09-19T01:03:12.998253Z","end":"2026-09-19T01:03:13.177123Z","steps":["trace[534063207] 'process raft request' (duration: 178.360131ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.17744Z","caller":"traceutil/trace.go:171","msg":"trace[1314782494] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"176.744727ms","start":"2026-09-19T01:03:13.000689Z","end":"2026-09-19T01:03:13.177434Z","steps":["trace[1314782494] 'process raft request' (duration: 176.381837ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.177448Z","caller":"traceutil/trace.go:171","msg":"trace[271564533] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"172.220281ms","start":"2026-09-19T01:03:13.005215Z","end":"2026-09-19T01:03:13.177435Z","steps":["trace[271564533] 'process raft request' (duration: 172.114248ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.177617Z","caller":"traceutil/trace.go:171","msg":"trace[618285255] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"170.043121ms","start":"2026-09-19T01:03:13.007569Z","end":"2026-09-19T01:03:13.177612Z","steps":["trace[618285255] 'process raft request' (duration: 169.825555ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.17785Z","caller":"traceutil/trace.go:171","msg":"trace[646693971] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"160.740545ms","start":"2026-09-19T01:03:13.017104Z","end":"2026-09-19T01:03:13.177845Z","steps":["trace[646693971] 'process raft request' (duration: 160.581ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.177844Z","caller":"traceutil/trace.go:171","msg":"trace[1886119585] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"169.528508ms","start":"2026-09-19T01:03:13.008269Z","end":"2026-09-19T01:03:13.177797Z","steps":["trace[1886119585] 'process raft request' (duration: 169.28804ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.177966Z","caller":"traceutil/trace.go:171","msg":"trace[1847008280] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"153.204017ms","start":"2026-09-19T01:03:13.024757Z","end":"2026-09-19T01:03:13.177962Z","steps":["trace[1847008280] 'process raft request' (duration: 152.993981ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:13.178043Z","caller":"traceutil/trace.go:171","msg":"trace[243432503] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"112.176215ms","start":"2026-09-19T01:03:13.065858Z","end":"2026-09-19T01:03:13.178034Z","steps":["trace[243432503] 'process raft request' (duration: 112.053282ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.056269Z","caller":"traceutil/trace.go:171","msg":"trace[316106030] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"156.594353ms","start":"2026-09-19T01:03:19.899616Z","end":"2026-09-19T01:03:20.056211Z","steps":["trace[316106030] 'process raft request' (duration: 156.370657ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.090879Z","caller":"traceutil/trace.go:171","msg":"trace[254052240] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"184.03344ms","start":"2026-09-19T01:03:19.906818Z","end":"2026-09-19T01:03:20.090851Z","steps":["trace[254052240] 'process raft request' (duration: 183.418273ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.732273Z","caller":"traceutil/trace.go:171","msg":"trace[2079549851] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"569.105531ms","start":"2026-09-19T01:03:20.163142Z","end":"2026-09-19T01:03:20.732247Z","steps":["trace[2079549851] 'process raft request' (duration: 568.961167ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.732633Z","caller":"traceutil/trace.go:171","msg":"trace[891180767] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:680; }","duration":"343.479011ms","start":"2026-09-19T01:03:20.389119Z","end":"2026-09-19T01:03:20.732598Z","steps":["trace[891180767] 'read index received' (duration: 343.456111ms)","trace[891180767] 'applied index is now lower than readState.Index' (duration: 19.46µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:03:20.732985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.736644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T01:03:20.732975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.829021ms","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-19T01:03:20.732987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.680424ms","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-09-19T01:03:20.733028Z","caller":"traceutil/trace.go:171","msg":"trace[1682323051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"282.797786ms","start":"2026-09-19T01:03:20.450217Z","end":"2026-09-19T01:03:20.733014Z","steps":["trace[1682323051] 'agreement among raft nodes before linearized reading' (duration: 282.664852ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.733037Z","caller":"traceutil/trace.go:171","msg":"trace[840399854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"343.940424ms","start":"2026-09-19T01:03:20.38908Z","end":"2026-09-19T01:03:20.73302Z","steps":["trace[840399854] 'agreement among raft nodes before linearized reading' (duration: 343.660996ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:20.73305Z","caller":"traceutil/trace.go:171","msg":"trace[818186097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"286.757486ms","start":"2026-09-19T01:03:20.446275Z","end":"2026-09-19T01:03:20.733033Z","steps":["trace[818186097] 'agreement among raft nodes before linearized reading' (duration: 286.52704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:20.733067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:03:20.389062Z","time spent":"343.996756ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T01:03:20.733243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.073225ms","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-19T01:03:20.733273Z","caller":"traceutil/trace.go:171","msg":"trace[1624875240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"283.140506ms","start":"2026-09-19T01:03:20.450122Z","end":"2026-09-19T01:03:20.733263Z","steps":["trace[1624875240] 'agreement among raft nodes before linearized reading' (duration: 283.053894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:20.733274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:03:20.163117Z","time spent":"569.223743ms","remote":"127.0.0.1:51532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:03:22.209424Z","caller":"traceutil/trace.go:171","msg":"trace[470547968] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"175.358381ms","start":"2026-09-19T01:03:22.034044Z","end":"2026-09-19T01:03:22.209403Z","steps":["trace[470547968] 'process raft request' (duration: 175.244997ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:22.222402Z","caller":"traceutil/trace.go:171","msg":"trace[880692308] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"186.690263ms","start":"2026-09-19T01:03:22.035691Z","end":"2026-09-19T01:03:22.222381Z","steps":["trace[880692308] 'process raft request' (duration: 186.528569ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:36.222581Z","caller":"traceutil/trace.go:171","msg":"trace[873777721] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"120.157457ms","start":"2026-09-19T01:03:36.102399Z","end":"2026-09-19T01:03:36.222556Z","steps":["trace[873777721] 'read index received' (duration: 120.035204ms)","trace[873777721] 'applied index is now lower than readState.Index' (duration: 121.183µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:03:36.222885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.471695ms","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-19T01:03:36.222951Z","caller":"traceutil/trace.go:171","msg":"trace[192439720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"120.585108ms","start":"2026-09-19T01:03:36.10235Z","end":"2026-09-19T01:03:36.222935Z","steps":["trace[192439720] 'agreement among raft nodes before linearized reading' (duration: 120.372732ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:36.222741Z","caller":"traceutil/trace.go:171","msg":"trace[2076867178] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"222.78352ms","start":"2026-09-19T01:03:35.999936Z","end":"2026-09-19T01:03:36.222719Z","steps":["trace[2076867178] 'process raft request' (duration: 222.435121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.620741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8916741020879553590,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:04:17.721869Z","caller":"traceutil/trace.go:171","msg":"trace[836984680] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"601.754082ms","start":"2026-09-19T01:04:17.120088Z","end":"2026-09-19T01:04:17.721842Z","steps":["trace[836984680] 'read index received' (duration: 601.592667ms)","trace[836984680] 'applied index is now lower than readState.Index' (duration: 160.295µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:17.72201Z","caller":"traceutil/trace.go:171","msg":"trace[1247658240] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"613.145096ms","start":"2026-09-19T01:04:17.10885Z","end":"2026-09-19T01:04:17.721995Z","steps":["trace[1247658240] 'process raft request' (duration: 612.834907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.722285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.183874ms","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-19T01:04:17.722388Z","caller":"traceutil/trace.go:171","msg":"trace[1114503267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"602.307707ms","start":"2026-09-19T01:04:17.120039Z","end":"2026-09-19T01:04:17.722347Z","steps":["trace[1114503267] 'agreement among raft nodes before linearized reading' (duration: 602.0454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.722392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:17.10883Z","time spent":"613.208298ms","remote":"127.0.0.1:51564","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-09-19T01:04:17.722442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:17.120019Z","time spent":"602.40905ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T01:04:17.722731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.68009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-09-19T01:04:17.722766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.53744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:17.722825Z","caller":"traceutil/trace.go:171","msg":"trace[1849925725] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1102; }","duration":"236.638323ms","start":"2026-09-19T01:04:17.486171Z","end":"2026-09-19T01:04:17.722809Z","steps":["trace[1849925725] 'agreement among raft nodes before linearized reading' (duration: 236.548031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.722825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.726791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T01:04:17.722865Z","caller":"traceutil/trace.go:171","msg":"trace[614368720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"551.823273ms","start":"2026-09-19T01:04:17.17103Z","end":"2026-09-19T01:04:17.722853Z","steps":["trace[614368720] 'agreement among raft nodes before linearized reading' (duration: 551.69828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.722896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:17.171009Z","time spent":"551.881035ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T01:04:17.722783Z","caller":"traceutil/trace.go:171","msg":"trace[601428992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"572.753192ms","start":"2026-09-19T01:04:17.150015Z","end":"2026-09-19T01:04:17.722768Z","steps":["trace[601428992] 'agreement among raft nodes before linearized reading' (duration: 572.599568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:17.722976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:17.150007Z","time spent":"572.961477ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:04:34.27939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.022769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:04:34.279544Z","caller":"traceutil/trace.go:171","msg":"trace[1754690514] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"413.434619ms","start":"2026-09-19T01:04:33.866096Z","end":"2026-09-19T01:04:34.279531Z","steps":["trace[1754690514] 'process raft request' (duration: 413.371097ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:34.279548Z","caller":"traceutil/trace.go:171","msg":"trace[108399718] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"413.510962ms","start":"2026-09-19T01:04:33.866025Z","end":"2026-09-19T01:04:34.279536Z","steps":["trace[108399718] 'process raft request' (duration: 221.280697ms)","trace[108399718] 'compare' (duration: 191.943217ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:34.279588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:33.866069Z","time spent":"413.496551ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:04:34.279597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:33.866006Z","time spent":"413.566904ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:04:34.332909Z","caller":"traceutil/trace.go:171","msg":"trace[1618003315] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"179.988067ms","start":"2026-09-19T01:04:34.152903Z","end":"2026-09-19T01:04:34.332891Z","steps":["trace[1618003315] 'read index received' (duration: 179.826722ms)","trace[1618003315] 'applied index is now lower than readState.Index' (duration: 160.305µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:34.333006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.083079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:34.333049Z","caller":"traceutil/trace.go:171","msg":"trace[1169585835] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1166; }","duration":"180.156842ms","start":"2026-09-19T01:04:34.15288Z","end":"2026-09-19T01:04:34.333037Z","steps":["trace[1169585835] 'agreement among raft nodes before linearized reading' (duration: 180.07953ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:34.33311Z","caller":"traceutil/trace.go:171","msg":"trace[134756143] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"233.203816ms","start":"2026-09-19T01:04:34.099747Z","end":"2026-09-19T01:04:34.332951Z","steps":["trace[134756143] 'process raft request' (duration: 233.044451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:34.628802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.757057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:04:34.628887Z","caller":"traceutil/trace.go:171","msg":"trace[1456406199] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1166; }","duration":"135.896981ms","start":"2026-09-19T01:04:34.492971Z","end":"2026-09-19T01:04:34.628868Z","steps":["trace[1456406199] 'count revisions from in-memory index tree' (duration: 135.613443ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:34.644093Z","caller":"traceutil/trace.go:171","msg":"trace[863382862] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"104.908004ms","start":"2026-09-19T01:04:34.539124Z","end":"2026-09-19T01:04:34.644032Z","steps":["trace[863382862] 'process raft request' (duration: 104.637327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:50.540355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.620242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:04:50.540614Z","caller":"traceutil/trace.go:171","msg":"trace[194107554] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"518.723255ms","start":"2026-09-19T01:04:50.021832Z","end":"2026-09-19T01:04:50.540555Z","steps":["trace[194107554] 'process raft request' (duration: 115.708123ms)","trace[194107554] 'compare' (duration: 402.25079ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:50.540669Z","caller":"traceutil/trace.go:171","msg":"trace[1887840941] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"177.067927ms","start":"2026-09-19T01:04:50.363575Z","end":"2026-09-19T01:04:50.540642Z","steps":["trace[1887840941] 'read index received' (duration: 31.441µs)","trace[1887840941] 'applied index is now lower than readState.Index' (duration: 177.033335ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:50.540841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:50.021809Z","time spent":"518.90119ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:04:50.540903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.320373ms","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:532"} {"level":"info","ts":"2026-09-19T01:04:50.540957Z","caller":"traceutil/trace.go:171","msg":"trace[1222144864] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1278; }","duration":"177.415856ms","start":"2026-09-19T01:04:50.363527Z","end":"2026-09-19T01:04:50.540943Z","steps":["trace[1222144864] 'agreement among raft nodes before linearized reading' (duration: 177.242381ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:50.541298Z","caller":"traceutil/trace.go:171","msg":"trace[656716773] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"136.450366ms","start":"2026-09-19T01:04:50.404832Z","end":"2026-09-19T01:04:50.541282Z","steps":["trace[656716773] 'process raft request' (duration: 135.764267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:50.541472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.916594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T01:04:50.541607Z","caller":"traceutil/trace.go:171","msg":"trace[1847944729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"110.041567ms","start":"2026-09-19T01:04:50.431532Z","end":"2026-09-19T01:04:50.541574Z","steps":["trace[1847944729] 'agreement among raft nodes before linearized reading' (duration: 109.77115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:50.54176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.974641ms","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-19T01:04:50.541869Z","caller":"traceutil/trace.go:171","msg":"trace[1778183741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"141.109955ms","start":"2026-09-19T01:04:50.400741Z","end":"2026-09-19T01:04:50.541851Z","steps":["trace[1778183741] 'agreement among raft nodes before linearized reading' (duration: 140.480907ms)"],"step_count":1}