{"level":"warn","ts":"2026-07-01T09:58:07.953438Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T09:58:07.953521Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-07-01T09:58:07.953587Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T09:58:07.953599Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T09:58:07.953613Z","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-07-01T09:58:07.954216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-01T09:58:07.954288Z","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://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-07-01T09:58:07.963442Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.992659ms"} {"level":"info","ts":"2026-07-01T09:58:07.971905Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-01T09:58:07.972066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T09:58:07.972117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-01T09:58:07.972152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T09:58:07.972172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-01T09:58:07.97225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-01T09:58:07.983311Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T09:58:07.985134Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T09:58:07.986791Z","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-07-01T09:58:07.990948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T09:58:07.996849Z","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-07-01T09:58:07.997029Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T09:58:07.997056Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T09:58:07.99785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T09:58:07.997868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T09:58:07.997899Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T09:58:07.997998Z","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-07-01T09:58:07.998026Z","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-07-01T09:58:07.998052Z","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-07-01T09:58:07.998534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-01T09:58:07.99863Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T09:58:08.579454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T09:58:08.579506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T09:58:08.579534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-01T09:58:08.57955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-01T09:58:08.579558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T09:58:08.579574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-01T09:58:08.579587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T09:58:08.580648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T09:58:08.580709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T09:58:08.580807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T09:58:08.580848Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:58:08.582287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-01T09:58:08.582427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:58:08.582547Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:58:08.582576Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:58:08.582932Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T09:58:08.583007Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T09:58:08.583963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T09:58:32.820897Z","caller":"traceutil/trace.go:171","msg":"trace[1056587467] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"341.482819ms","start":"2026-07-01T09:58:32.479378Z","end":"2026-07-01T09:58:32.820861Z","steps":["trace[1056587467] 'process raft request' (duration: 341.276832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:32.821824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:32.479349Z","time spent":"341.717276ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:58:44.134537Z","caller":"traceutil/trace.go:171","msg":"trace[1469679738] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"198.705866ms","start":"2026-07-01T09:58:43.935813Z","end":"2026-07-01T09:58:44.134519Z","steps":["trace[1469679738] 'process raft request' (duration: 198.629964ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.144162Z","caller":"traceutil/trace.go:171","msg":"trace[228340100] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"204.227678ms","start":"2026-07-01T09:58:43.939913Z","end":"2026-07-01T09:58:44.144141Z","steps":["trace[228340100] 'process raft request' (duration: 203.505396ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.144178Z","caller":"traceutil/trace.go:171","msg":"trace[1907849188] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"204.190548ms","start":"2026-07-01T09:58:43.939837Z","end":"2026-07-01T09:58:44.144028Z","steps":["trace[1907849188] 'process raft request' (duration: 203.29961ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.144681Z","caller":"traceutil/trace.go:171","msg":"trace[1628306067] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"202.155394ms","start":"2026-07-01T09:58:43.94251Z","end":"2026-07-01T09:58:44.144666Z","steps":["trace[1628306067] 'process raft request' (duration: 201.037389ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.145085Z","caller":"traceutil/trace.go:171","msg":"trace[1102674623] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"199.374617ms","start":"2026-07-01T09:58:43.945684Z","end":"2026-07-01T09:58:44.145058Z","steps":["trace[1102674623] 'process raft request' (duration: 198.618914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.145103Z","caller":"traceutil/trace.go:171","msg":"trace[1785335384] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"202.405582ms","start":"2026-07-01T09:58:43.942581Z","end":"2026-07-01T09:58:44.144986Z","steps":["trace[1785335384] 'process raft request' (duration: 201.156363ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.147119Z","caller":"traceutil/trace.go:171","msg":"trace[1500559614] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"199.202922ms","start":"2026-07-01T09:58:43.947899Z","end":"2026-07-01T09:58:44.147102Z","steps":["trace[1500559614] 'process raft request' (duration: 196.792037ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.147294Z","caller":"traceutil/trace.go:171","msg":"trace[15018656] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"198.939493ms","start":"2026-07-01T09:58:43.948336Z","end":"2026-07-01T09:58:44.147275Z","steps":["trace[15018656] 'process raft request' (duration: 198.678875ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.152037Z","caller":"traceutil/trace.go:171","msg":"trace[349250027] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"181.81937ms","start":"2026-07-01T09:58:43.966288Z","end":"2026-07-01T09:58:44.148108Z","steps":["trace[349250027] 'process raft request' (duration: 181.50526ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.152541Z","caller":"traceutil/trace.go:171","msg":"trace[561160848] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"155.450107ms","start":"2026-07-01T09:58:43.997082Z","end":"2026-07-01T09:58:44.152532Z","steps":["trace[561160848] 'process raft request' (duration: 150.935366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.152631Z","caller":"traceutil/trace.go:171","msg":"trace[383340181] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:498; }","duration":"120.560369ms","start":"2026-07-01T09:58:44.032049Z","end":"2026-07-01T09:58:44.152609Z","steps":["trace[383340181] 'read index received' (duration: 102.654581ms)","trace[383340181] 'applied index is now lower than readState.Index' (duration: 17.905138ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:58:44.152861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.807187ms","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-07-01T09:58:44.152891Z","caller":"traceutil/trace.go:171","msg":"trace[1681953571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"120.880279ms","start":"2026-07-01T09:58:44.032004Z","end":"2026-07-01T09:58:44.152884Z","steps":["trace[1681953571] 'agreement among raft nodes before linearized reading' (duration: 120.802547ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.15396Z","caller":"traceutil/trace.go:171","msg":"trace[539872929] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"181.471899ms","start":"2026-07-01T09:58:43.966628Z","end":"2026-07-01T09:58:44.1481Z","steps":["trace[539872929] 'process raft request' (duration: 181.296893ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.436324Z","caller":"traceutil/trace.go:171","msg":"trace[65288330] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"214.487389ms","start":"2026-07-01T09:58:44.221811Z","end":"2026-07-01T09:58:44.436299Z","steps":["trace[65288330] 'process raft request' (duration: 210.973289ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437209Z","caller":"traceutil/trace.go:171","msg":"trace[723567234] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"209.327178ms","start":"2026-07-01T09:58:44.22781Z","end":"2026-07-01T09:58:44.437137Z","steps":["trace[723567234] 'process raft request' (duration: 209.175863ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437324Z","caller":"traceutil/trace.go:171","msg":"trace[802682462] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"209.187384ms","start":"2026-07-01T09:58:44.228132Z","end":"2026-07-01T09:58:44.437319Z","steps":["trace[802682462] 'process raft request' (duration: 208.937006ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437374Z","caller":"traceutil/trace.go:171","msg":"trace[428437323] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"209.09028ms","start":"2026-07-01T09:58:44.228277Z","end":"2026-07-01T09:58:44.437367Z","steps":["trace[428437323] 'process raft request' (duration: 208.846402ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437484Z","caller":"traceutil/trace.go:171","msg":"trace[1323474784] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"209.09166ms","start":"2026-07-01T09:58:44.228385Z","end":"2026-07-01T09:58:44.437477Z","steps":["trace[1323474784] 'process raft request' (duration: 208.955256ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437444Z","caller":"traceutil/trace.go:171","msg":"trace[1946283422] transaction","detail":"{read_only:false; number_of_response:1; response_revision:510; }","duration":"209.119741ms","start":"2026-07-01T09:58:44.228285Z","end":"2026-07-01T09:58:44.437405Z","steps":["trace[1946283422] 'process raft request' (duration: 209.014308ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:44.437525Z","caller":"traceutil/trace.go:171","msg":"trace[778737853] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"175.485012ms","start":"2026-07-01T09:58:44.262022Z","end":"2026-07-01T09:58:44.437507Z","steps":["trace[778737853] 'process raft request' (duration: 175.385739ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:50.523848Z","caller":"traceutil/trace.go:171","msg":"trace[497506708] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"328.66077ms","start":"2026-07-01T09:58:50.195163Z","end":"2026-07-01T09:58:50.523824Z","steps":["trace[497506708] 'read index received' (duration: 326.780461ms)","trace[497506708] 'applied index is now lower than readState.Index' (duration: 1.879169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:58:50.524009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.819245ms","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-07-01T09:58:50.524077Z","caller":"traceutil/trace.go:171","msg":"trace[1549355239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"328.929199ms","start":"2026-07-01T09:58:50.19513Z","end":"2026-07-01T09:58:50.524059Z","steps":["trace[1549355239] 'agreement among raft nodes before linearized reading' (duration: 328.786754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:50.524241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.195114Z","time spent":"329.077794ms","remote":"127.0.0.1:43616","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":"info","ts":"2026-07-01T09:58:50.524018Z","caller":"traceutil/trace.go:171","msg":"trace[228159743] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"342.270871ms","start":"2026-07-01T09:58:50.181631Z","end":"2026-07-01T09:58:50.523902Z","steps":["trace[228159743] 'process raft request' (duration: 340.620409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:50.524705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.181603Z","time spent":"343.006944ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:50.582848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.294963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c9vfb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T09:58:50.582942Z","caller":"traceutil/trace.go:171","msg":"trace[1419886831] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c9vfb; range_end:; response_count:1; response_revision:586; }","duration":"191.441248ms","start":"2026-07-01T09:58:50.391478Z","end":"2026-07-01T09:58:50.58292Z","steps":["trace[1419886831] 'agreement among raft nodes before linearized reading' (duration: 191.183599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:50.583332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.028089ms","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-07-01T09:58:50.583432Z","caller":"traceutil/trace.go:171","msg":"trace[1489114238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"388.173864ms","start":"2026-07-01T09:58:50.19524Z","end":"2026-07-01T09:58:50.583414Z","steps":["trace[1489114238] 'agreement among raft nodes before linearized reading' (duration: 387.754251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:50.583469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.195219Z","time spent":"388.241226ms","remote":"127.0.0.1:43616","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-07-01T09:58:51.232235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.071157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T09:58:51.23254Z","caller":"traceutil/trace.go:171","msg":"trace[938647413] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"648.618815ms","start":"2026-07-01T09:58:50.583903Z","end":"2026-07-01T09:58:51.232522Z","steps":["trace[938647413] 'read index received' (duration: 19.112054ms)","trace[938647413] 'applied index is now lower than readState.Index' (duration: 629.505211ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:58:51.232647Z","caller":"traceutil/trace.go:171","msg":"trace[1535035064] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"703.487301ms","start":"2026-07-01T09:58:50.529146Z","end":"2026-07-01T09:58:51.232633Z","steps":["trace[1535035064] 'process raft request' (duration: 73.793405ms)","trace[1535035064] 'compare' (duration: 628.948713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:58:51.232746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.529119Z","time spent":"703.591765ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:51.23282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.861022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T09:58:51.23293Z","caller":"traceutil/trace.go:171","msg":"trace[1639625689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"649.011277ms","start":"2026-07-01T09:58:50.583899Z","end":"2026-07-01T09:58:51.23291Z","steps":["trace[1639625689] 'agreement among raft nodes before linearized reading' (duration: 648.768139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:51.233007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.583853Z","time spent":"649.142562ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T09:58:51.319335Z","caller":"traceutil/trace.go:171","msg":"trace[1268034275] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"732.872561ms","start":"2026-07-01T09:58:50.586421Z","end":"2026-07-01T09:58:51.319294Z","steps":["trace[1268034275] 'process raft request' (duration: 732.681945ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:51.319429Z","caller":"traceutil/trace.go:171","msg":"trace[1166932693] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"730.981131ms","start":"2026-07-01T09:58:50.588412Z","end":"2026-07-01T09:58:51.319393Z","steps":["trace[1166932693] 'process raft request' (duration: 730.797875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:51.319509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.6145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T09:58:51.319534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.586403Z","time spent":"733.051567ms","remote":"127.0.0.1:43862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T09:58:51.319565Z","caller":"traceutil/trace.go:171","msg":"trace[433198276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"420.714593ms","start":"2026-07-01T09:58:50.898838Z","end":"2026-07-01T09:58:51.319553Z","steps":["trace[433198276] 'agreement among raft nodes before linearized reading' (duration: 420.498546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:51.319642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.898816Z","time spent":"420.817237ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T09:58:51.319689Z","caller":"traceutil/trace.go:171","msg":"trace[546735873] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"720.50087ms","start":"2026-07-01T09:58:50.599092Z","end":"2026-07-01T09:58:51.319592Z","steps":["trace[546735873] 'process raft request' (duration: 720.17515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:51.319862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.599063Z","time spent":"720.715407ms","remote":"127.0.0.1:43548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:51.320268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:50.58838Z","time spent":"731.836449ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:52.072046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.3186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T09:58:52.072168Z","caller":"traceutil/trace.go:171","msg":"trace[290353092] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"741.487533ms","start":"2026-07-01T09:58:51.330658Z","end":"2026-07-01T09:58:52.072145Z","steps":["trace[290353092] 'read index received' (duration: 52.928595ms)","trace[290353092] 'applied index is now lower than readState.Index' (duration: 688.556308ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:58:52.072244Z","caller":"traceutil/trace.go:171","msg":"trace[1168279795] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"747.259765ms","start":"2026-07-01T09:58:51.324972Z","end":"2026-07-01T09:58:52.072231Z","steps":["trace[1168279795] 'process raft request' (duration: 58.659666ms)","trace[1168279795] 'compare' (duration: 688.174586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:58:52.072301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:51.32495Z","time spent":"747.321377ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:52.072428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.215473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T09:58:52.0725Z","caller":"traceutil/trace.go:171","msg":"trace[589865991] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:591; }","duration":"588.324266ms","start":"2026-07-01T09:58:51.484159Z","end":"2026-07-01T09:58:52.072483Z","steps":["trace[589865991] 'agreement among raft nodes before linearized reading' (duration: 588.210412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:52.072518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.684569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T09:58:52.072548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:51.48411Z","time spent":"588.425089ms","remote":"127.0.0.1:43390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-07-01T09:58:52.072573Z","caller":"traceutil/trace.go:171","msg":"trace[148066041] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:591; }","duration":"741.937407ms","start":"2026-07-01T09:58:51.330618Z","end":"2026-07-01T09:58:52.072556Z","steps":["trace[148066041] 'agreement among raft nodes before linearized reading' (duration: 741.635038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:52.07269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:51.330603Z","time spent":"742.069081ms","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-01T09:58:52.072808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.109845ms","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-07-01T09:58:52.072943Z","caller":"traceutil/trace.go:171","msg":"trace[137848457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"135.295031ms","start":"2026-07-01T09:58:51.937631Z","end":"2026-07-01T09:58:52.072926Z","steps":["trace[137848457] 'agreement among raft nodes before linearized reading' (duration: 135.014952ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:58:52.489384Z","caller":"traceutil/trace.go:171","msg":"trace[1544865909] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"411.623756ms","start":"2026-07-01T09:58:52.077744Z","end":"2026-07-01T09:58:52.489368Z","steps":["trace[1544865909] 'process raft request' (duration: 411.539784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:58:52.489491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:52.077723Z","time spent":"411.715379ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:58:52.48961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:58:52.07364Z","time spent":"415.969393ms","remote":"127.0.0.1:43390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-01T09:59:05.670402Z","caller":"traceutil/trace.go:171","msg":"trace[1896338939] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"185.396848ms","start":"2026-07-01T09:59:05.484985Z","end":"2026-07-01T09:59:05.670382Z","steps":["trace[1896338939] 'process raft request' (duration: 185.263803ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:59:08.322749Z","caller":"traceutil/trace.go:171","msg":"trace[535191758] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"179.900742ms","start":"2026-07-01T09:59:08.142827Z","end":"2026-07-01T09:59:08.322728Z","steps":["trace[535191758] 'read index received' (duration: 179.716646ms)","trace[535191758] 'applied index is now lower than readState.Index' (duration: 183.256µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:59:08.32296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.090448ms","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-07-01T09:59:08.322987Z","caller":"traceutil/trace.go:171","msg":"trace[455093054] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"464.738365ms","start":"2026-07-01T09:59:07.858223Z","end":"2026-07-01T09:59:08.322961Z","steps":["trace[455093054] 'process raft request' (duration: 464.379014ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:59:08.323025Z","caller":"traceutil/trace.go:171","msg":"trace[1422697913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"180.213942ms","start":"2026-07-01T09:59:08.142797Z","end":"2026-07-01T09:59:08.323011Z","steps":["trace[1422697913] 'agreement among raft nodes before linearized reading' (duration: 180.016066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:59:08.323137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:59:07.858201Z","time spent":"464.85939ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:59:42.984045Z","caller":"traceutil/trace.go:171","msg":"trace[2062544265] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"471.619254ms","start":"2026-07-01T09:59:42.512406Z","end":"2026-07-01T09:59:42.984025Z","steps":["trace[2062544265] 'process raft request' (duration: 471.47472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:59:42.984184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:59:42.512395Z","time spent":"471.717668ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:59:42.984128Z","caller":"traceutil/trace.go:171","msg":"trace[1725774693] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1009; }","duration":"369.207363ms","start":"2026-07-01T09:59:42.614873Z","end":"2026-07-01T09:59:42.984081Z","steps":["trace[1725774693] 'read index received' (duration: 369.190532ms)","trace[1725774693] 'applied index is now lower than readState.Index' (duration: 14.071µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:59:42.984429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.527764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T09:59:42.984514Z","caller":"traceutil/trace.go:171","msg":"trace[1733358830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"369.666758ms","start":"2026-07-01T09:59:42.614824Z","end":"2026-07-01T09:59:42.984491Z","steps":["trace[1733358830] 'agreement among raft nodes before linearized reading' (duration: 369.360248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:59:42.984559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:59:42.614803Z","time spent":"369.74534ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T09:59:42.986056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.342673ms","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-07-01T09:59:42.986119Z","caller":"traceutil/trace.go:171","msg":"trace[1530503605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"281.415145ms","start":"2026-07-01T09:59:42.704689Z","end":"2026-07-01T09:59:42.986104Z","steps":["trace[1530503605] 'agreement among raft nodes before linearized reading' (duration: 281.209929ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:59:42.986396Z","caller":"traceutil/trace.go:171","msg":"trace[902157999] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"327.574686ms","start":"2026-07-01T09:59:42.658811Z","end":"2026-07-01T09:59:42.986385Z","steps":["trace[902157999] 'process raft request' (duration: 326.990338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:59:42.986498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:59:42.658783Z","time spent":"327.639738ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:05.48583Z","caller":"traceutil/trace.go:171","msg":"trace[1796724064] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"422.674173ms","start":"2026-07-01T10:00:05.063139Z","end":"2026-07-01T10:00:05.485813Z","steps":["trace[1796724064] 'process raft request' (duration: 422.55163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:05.485948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:05.063114Z","time spent":"422.770327ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:05.486297Z","caller":"traceutil/trace.go:171","msg":"trace[1709593778] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1085; }","duration":"405.779718ms","start":"2026-07-01T10:00:05.080465Z","end":"2026-07-01T10:00:05.486244Z","steps":["trace[1709593778] 'read index received' (duration: 405.764078ms)","trace[1709593778] 'applied index is now lower than readState.Index' (duration: 12.69µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:00:05.486437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.976795ms","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-07-01T10:00:05.48649Z","caller":"traceutil/trace.go:171","msg":"trace[682134509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"406.054637ms","start":"2026-07-01T10:00:05.080414Z","end":"2026-07-01T10:00:05.486469Z","steps":["trace[682134509] 'agreement among raft nodes before linearized reading' (duration: 405.927323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:05.486518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:05.080394Z","time spent":"406.11669ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T10:00:05.638784Z","caller":"traceutil/trace.go:171","msg":"trace[1676440513] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"166.327921ms","start":"2026-07-01T10:00:05.472422Z","end":"2026-07-01T10:00:05.63875Z","steps":["trace[1676440513] 'process raft request' (duration: 131.821507ms)","trace[1676440513] 'compare' (duration: 34.231975ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:05.638811Z","caller":"traceutil/trace.go:171","msg":"trace[184243935] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"152.393029ms","start":"2026-07-01T10:00:05.486388Z","end":"2026-07-01T10:00:05.638781Z","steps":["trace[184243935] 'read index received' (duration: 117.836194ms)","trace[184243935] 'applied index is now lower than readState.Index' (duration: 34.555024ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:05.638804Z","caller":"traceutil/trace.go:171","msg":"trace[571442725] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"148.462574ms","start":"2026-07-01T10:00:05.490314Z","end":"2026-07-01T10:00:05.638777Z","steps":["trace[571442725] 'process raft request' (duration: 148.402983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:05.639174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.606373ms","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-07-01T10:00:05.639217Z","caller":"traceutil/trace.go:171","msg":"trace[1044318050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"553.648244ms","start":"2026-07-01T10:00:05.085558Z","end":"2026-07-01T10:00:05.639206Z","steps":["trace[1044318050] 'agreement among raft nodes before linearized reading' (duration: 553.53115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:05.639251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:05.08551Z","time spent":"553.733386ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:05.639054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.297222ms","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-07-01T10:00:05.639356Z","caller":"traceutil/trace.go:171","msg":"trace[1726338815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"553.577172ms","start":"2026-07-01T10:00:05.085717Z","end":"2026-07-01T10:00:05.639295Z","steps":["trace[1726338815] 'agreement among raft nodes before linearized reading' (duration: 553.19579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:05.6395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:05.085711Z","time spent":"553.725786ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:07.122252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.71752ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T10:00:07.965172Z","caller":"traceutil/trace.go:171","msg":"trace[900249644] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"258.682678ms","start":"2026-07-01T10:00:07.706465Z","end":"2026-07-01T10:00:07.965148Z","steps":["trace[900249644] 'process raft request' (duration: 258.550413ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:07.985717Z","caller":"traceutil/trace.go:171","msg":"trace[659366533] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"278.868756ms","start":"2026-07-01T10:00:07.706819Z","end":"2026-07-01T10:00:07.985688Z","steps":["trace[659366533] 'process raft request' (duration: 278.717422ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:07.985805Z","caller":"traceutil/trace.go:171","msg":"trace[2107663060] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1094; }","duration":"193.245534ms","start":"2026-07-01T10:00:07.792547Z","end":"2026-07-01T10:00:07.985792Z","steps":["trace[2107663060] 'read index received' (duration: 172.388423ms)","trace[2107663060] 'applied index is now lower than readState.Index' (duration: 20.84838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:00:07.985994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.391278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T10:00:07.986093Z","caller":"traceutil/trace.go:171","msg":"trace[1006797827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"193.592085ms","start":"2026-07-01T10:00:07.792489Z","end":"2026-07-01T10:00:07.986081Z","steps":["trace[1006797827] 'agreement among raft nodes before linearized reading' (duration: 193.346307ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:07.986304Z","caller":"traceutil/trace.go:171","msg":"trace[711427333] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"278.97044ms","start":"2026-07-01T10:00:07.70691Z","end":"2026-07-01T10:00:07.98588Z","steps":["trace[711427333] 'process raft request' (duration: 278.745792ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:09.618316Z","caller":"traceutil/trace.go:171","msg":"trace[1698943306] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"118.249988ms","start":"2026-07-01T10:00:09.499779Z","end":"2026-07-01T10:00:09.618029Z","steps":["trace[1698943306] 'process raft request' (duration: 118.040741ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:23.847377Z","caller":"traceutil/trace.go:171","msg":"trace[1033984803] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"185.502849ms","start":"2026-07-01T10:00:23.661838Z","end":"2026-07-01T10:00:23.847341Z","steps":["trace[1033984803] 'process raft request' (duration: 185.293352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.361293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411447656312331,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:00:26.623762Z","caller":"traceutil/trace.go:171","msg":"trace[1676905184] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"771.90894ms","start":"2026-07-01T10:00:25.85182Z","end":"2026-07-01T10:00:26.623729Z","steps":["trace[1676905184] 'process raft request' (duration: 771.705844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.624021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:25.851796Z","time spent":"772.060525ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:26.68951Z","caller":"traceutil/trace.go:171","msg":"trace[151489314] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"828.299707ms","start":"2026-07-01T10:00:25.86119Z","end":"2026-07-01T10:00:26.68949Z","steps":["trace[151489314] 'read index received' (duration: 762.842773ms)","trace[151489314] 'applied index is now lower than readState.Index' (duration: 65.455674ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:26.689539Z","caller":"traceutil/trace.go:171","msg":"trace[1223352518] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"740.33094ms","start":"2026-07-01T10:00:25.949149Z","end":"2026-07-01T10:00:26.68948Z","steps":["trace[1223352518] 'process raft request' (duration: 738.789171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.689753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:25.949123Z","time spent":"740.538877ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:00:26.691477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.272199ms","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-07-01T10:00:26.691478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.020985ms","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-07-01T10:00:26.691552Z","caller":"traceutil/trace.go:171","msg":"trace[1461815044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1162; }","duration":"830.383933ms","start":"2026-07-01T10:00:25.861149Z","end":"2026-07-01T10:00:26.691533Z","steps":["trace[1461815044] 'agreement among raft nodes before linearized reading' (duration: 828.454922ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:26.691577Z","caller":"traceutil/trace.go:171","msg":"trace[444556717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1162; }","duration":"630.118197ms","start":"2026-07-01T10:00:26.061422Z","end":"2026-07-01T10:00:26.69154Z","steps":["trace[444556717] 'agreement among raft nodes before linearized reading' (duration: 629.969832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.691617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:26.06139Z","time spent":"630.21478ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:26.691616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:25.861129Z","time spent":"830.469146ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:26.691502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.028801ms","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-07-01T10:00:26.69194Z","caller":"traceutil/trace.go:171","msg":"trace[1706168659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"628.507666ms","start":"2026-07-01T10:00:26.06341Z","end":"2026-07-01T10:00:26.691917Z","steps":["trace[1706168659] 'agreement among raft nodes before linearized reading' (duration: 627.944378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.691993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:26.063393Z","time spent":"628.584469ms","remote":"127.0.0.1:43616","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-07-01T10:00:26.692016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.943438ms","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-07-01T10:00:26.69211Z","caller":"traceutil/trace.go:171","msg":"trace[1958293604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"628.090203ms","start":"2026-07-01T10:00:26.063998Z","end":"2026-07-01T10:00:26.692088Z","steps":["trace[1958293604] 'agreement among raft nodes before linearized reading' (duration: 627.249487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.692156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:26.063982Z","time spent":"628.164275ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:26.692137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.142995ms","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-07-01T10:00:26.69223Z","caller":"traceutil/trace.go:171","msg":"trace[2107357787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1162; }","duration":"652.258989ms","start":"2026-07-01T10:00:26.039952Z","end":"2026-07-01T10:00:26.69221Z","steps":["trace[2107357787] 'agreement among raft nodes before linearized reading' (duration: 651.267208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:26.692279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:26.039937Z","time spent":"652.330181ms","remote":"127.0.0.1:43616","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-07-01T10:00:43.256905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411447656312493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T10:00:43.443388Z","caller":"traceutil/trace.go:171","msg":"trace[1979220329] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"742.668803ms","start":"2026-07-01T10:00:42.700661Z","end":"2026-07-01T10:00:43.44333Z","steps":["trace[1979220329] 'process raft request' (duration: 742.515148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.443539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.700642Z","time spent":"742.822218ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:43.475684Z","caller":"traceutil/trace.go:171","msg":"trace[915055285] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"719.026984ms","start":"2026-07-01T10:00:42.756637Z","end":"2026-07-01T10:00:43.475664Z","steps":["trace[915055285] 'read index received' (duration: 686.555015ms)","trace[915055285] 'applied index is now lower than readState.Index' (duration: 32.471009ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:43.475802Z","caller":"traceutil/trace.go:171","msg":"trace[1739338959] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"269.007364ms","start":"2026-07-01T10:00:43.20678Z","end":"2026-07-01T10:00:43.475787Z","steps":["trace[1739338959] 'process raft request' (duration: 268.769446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.385755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:00:43.476059Z","caller":"traceutil/trace.go:171","msg":"trace[1875412803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"719.454497ms","start":"2026-07-01T10:00:42.756598Z","end":"2026-07-01T10:00:43.476053Z","steps":["trace[1875412803] 'agreement among raft nodes before linearized reading' (duration: 719.344074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.756582Z","time spent":"719.490718ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:00:43.476261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.792531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T10:00:43.476326Z","caller":"traceutil/trace.go:171","msg":"trace[552610721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"709.895514ms","start":"2026-07-01T10:00:42.766413Z","end":"2026-07-01T10:00:43.476308Z","steps":["trace[552610721] 'agreement among raft nodes before linearized reading' (duration: 709.726119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.766397Z","time spent":"709.959746ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:43.476376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.685229ms","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-07-01T10:00:43.476397Z","caller":"traceutil/trace.go:171","msg":"trace[1063326262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"698.73112ms","start":"2026-07-01T10:00:42.777659Z","end":"2026-07-01T10:00:43.47639Z","steps":["trace[1063326262] 'agreement among raft nodes before linearized reading' (duration: 698.671048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.777645Z","time spent":"698.764372ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T10:00:43.476546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.826729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T10:00:43.47658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.681012ms","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":"info","ts":"2026-07-01T10:00:43.476604Z","caller":"traceutil/trace.go:171","msg":"trace[1421344167] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1235; }","duration":"249.734614ms","start":"2026-07-01T10:00:43.226863Z","end":"2026-07-01T10:00:43.476597Z","steps":["trace[1421344167] 'agreement among raft nodes before linearized reading' (duration: 249.695582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.342125ms","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-07-01T10:00:43.476672Z","caller":"traceutil/trace.go:171","msg":"trace[467391708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"708.390047ms","start":"2026-07-01T10:00:42.768275Z","end":"2026-07-01T10:00:43.476665Z","steps":["trace[467391708] 'agreement among raft nodes before linearized reading' (duration: 708.321245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.768264Z","time spent":"708.428718ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T10:00:43.476576Z","caller":"traceutil/trace.go:171","msg":"trace[919237934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"707.889541ms","start":"2026-07-01T10:00:42.768678Z","end":"2026-07-01T10:00:43.476567Z","steps":["trace[919237934] 'agreement among raft nodes before linearized reading' (duration: 707.787907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:43.476732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:42.768666Z","time spent":"708.059226ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T10:00:43.585471Z","caller":"traceutil/trace.go:171","msg":"trace[284586910] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"104.992847ms","start":"2026-07-01T10:00:43.480444Z","end":"2026-07-01T10:00:43.585437Z","steps":["trace[284586910] 'process raft request' (duration: 104.740539ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:43.585514Z","caller":"traceutil/trace.go:171","msg":"trace[1864495577] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"104.539333ms","start":"2026-07-01T10:00:43.480941Z","end":"2026-07-01T10:00:43.58548Z","steps":["trace[1864495577] 'process raft request' (duration: 104.404999ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:43.58555Z","caller":"traceutil/trace.go:171","msg":"trace[454760122] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"104.46207ms","start":"2026-07-01T10:00:43.481054Z","end":"2026-07-01T10:00:43.585516Z","steps":["trace[454760122] 'process raft request' (duration: 104.356497ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:43.585696Z","caller":"traceutil/trace.go:171","msg":"trace[312967467] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"104.830371ms","start":"2026-07-01T10:00:43.480855Z","end":"2026-07-01T10:00:43.585685Z","steps":["trace[312967467] 'process raft request' (duration: 104.432629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:44.646201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.188097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:00:44.646299Z","caller":"traceutil/trace.go:171","msg":"trace[1629987886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"154.336421ms","start":"2026-07-01T10:00:44.491939Z","end":"2026-07-01T10:00:44.646276Z","steps":["trace[1629987886] 'range keys from in-memory index tree' (duration: 154.115804ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:45.791567Z","caller":"traceutil/trace.go:171","msg":"trace[607510117] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"138.903122ms","start":"2026-07-01T10:00:45.652644Z","end":"2026-07-01T10:00:45.791548Z","steps":["trace[607510117] 'process raft request' (duration: 138.804908ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:46.416308Z","caller":"traceutil/trace.go:171","msg":"trace[1705271510] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1293; }","duration":"695.446386ms","start":"2026-07-01T10:00:45.720837Z","end":"2026-07-01T10:00:46.416284Z","steps":["trace[1705271510] 'read index received' (duration: 70.570006ms)","trace[1705271510] 'applied index is now lower than readState.Index' (duration: 624.87515ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:46.416639Z","caller":"traceutil/trace.go:171","msg":"trace[2144306328] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"763.030288ms","start":"2026-07-01T10:00:45.653596Z","end":"2026-07-01T10:00:46.416626Z","steps":["trace[2144306328] 'process raft request' (duration: 762.418689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.41673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.65358Z","time spent":"763.087679ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:46.4169Z","caller":"traceutil/trace.go:171","msg":"trace[574651867] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"763.247134ms","start":"2026-07-01T10:00:45.653642Z","end":"2026-07-01T10:00:46.416889Z","steps":["trace[574651867] 'process raft request' (duration: 762.546602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.41695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.65362Z","time spent":"763.302196ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:00:46.417123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.254212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T10:00:46.417203Z","caller":"traceutil/trace.go:171","msg":"trace[1832928662] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1247; }","duration":"696.380526ms","start":"2026-07-01T10:00:45.720799Z","end":"2026-07-01T10:00:46.417179Z","steps":["trace[1832928662] 'agreement among raft nodes before linearized reading' (duration: 696.19757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.417243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.720785Z","time spent":"696.447828ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-01T10:00:46.417439Z","caller":"traceutil/trace.go:171","msg":"trace[908115376] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"762.994117ms","start":"2026-07-01T10:00:45.654434Z","end":"2026-07-01T10:00:46.417428Z","steps":["trace[908115376] 'process raft request' (duration: 761.820439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.417503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.654423Z","time spent":"763.047799ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:46.417577Z","caller":"traceutil/trace.go:171","msg":"trace[434503176] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"763.348588ms","start":"2026-07-01T10:00:45.654221Z","end":"2026-07-01T10:00:46.417569Z","steps":["trace[434503176] 'process raft request' (duration: 762.005545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.417619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.654214Z","time spent":"763.381409ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:00:46.74232Z","caller":"traceutil/trace.go:171","msg":"trace[1583252351] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1298; }","duration":"325.929838ms","start":"2026-07-01T10:00:46.416369Z","end":"2026-07-01T10:00:46.742299Z","steps":["trace[1583252351] 'read index received' (duration: 325.738372ms)","trace[1583252351] 'applied index is now lower than readState.Index' (duration: 190.616µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:00:46.742363Z","caller":"traceutil/trace.go:171","msg":"trace[944131244] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"547.498188ms","start":"2026-07-01T10:00:46.194843Z","end":"2026-07-01T10:00:46.742341Z","steps":["trace[944131244] 'process raft request' (duration: 547.280472ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:46.742413Z","caller":"traceutil/trace.go:171","msg":"trace[1691324458] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"390.831943ms","start":"2026-07-01T10:00:46.351571Z","end":"2026-07-01T10:00:46.742403Z","steps":["trace[1691324458] 'process raft request' (duration: 390.687709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.742483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:46.351522Z","time spent":"390.914366ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:00:46.742715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.532634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:00:46.742869Z","caller":"traceutil/trace.go:171","msg":"trace[988742821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"775.72993ms","start":"2026-07-01T10:00:45.967119Z","end":"2026-07-01T10:00:46.742848Z","steps":["trace[988742821] 'agreement among raft nodes before linearized reading' (duration: 775.446201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.742947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:45.967101Z","time spent":"775.833443ms","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T10:00:46.742889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:46.194818Z","time spent":"547.592391ms","remote":"127.0.0.1:43538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:00:46.755077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.259887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:00:46.755118Z","caller":"traceutil/trace.go:171","msg":"trace[325088617] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1249; }","duration":"325.329759ms","start":"2026-07-01T10:00:46.429778Z","end":"2026-07-01T10:00:46.755108Z","steps":["trace[325088617] 'agreement among raft nodes before linearized reading' (duration: 325.270267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:46.755142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:00:46.429761Z","time spent":"325.37598ms","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T10:00:46.75515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.279035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:00:46.755272Z","caller":"traceutil/trace.go:171","msg":"trace[1579756146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"264.385098ms","start":"2026-07-01T10:00:46.49081Z","end":"2026-07-01T10:00:46.755195Z","steps":["trace[1579756146] 'agreement among raft nodes before linearized reading' (duration: 264.265964ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:46.943037Z","caller":"traceutil/trace.go:171","msg":"trace[1688484839] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"189.907668ms","start":"2026-07-01T10:00:46.753098Z","end":"2026-07-01T10:00:46.943006Z","steps":["trace[1688484839] 'process raft request' (duration: 189.739633ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:46.977754Z","caller":"traceutil/trace.go:171","msg":"trace[1108560431] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"219.02713ms","start":"2026-07-01T10:00:46.758701Z","end":"2026-07-01T10:00:46.977728Z","steps":["trace[1108560431] 'process raft request' (duration: 216.649475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:00:55.647029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.541514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:00:55.647204Z","caller":"traceutil/trace.go:171","msg":"trace[407028485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"158.823012ms","start":"2026-07-01T10:00:55.488346Z","end":"2026-07-01T10:00:55.647169Z","steps":["trace[407028485] 'range keys from in-memory index tree' (duration: 158.430601ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:00:55.66553Z","caller":"traceutil/trace.go:171","msg":"trace[1926253192] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"163.926444ms","start":"2026-07-01T10:00:55.501575Z","end":"2026-07-01T10:00:55.665501Z","steps":["trace[1926253192] 'process raft request' (duration: 163.747278ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:04:36.950478Z","caller":"traceutil/trace.go:171","msg":"trace[895287314] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"260.536471ms","start":"2026-07-01T10:04:36.689916Z","end":"2026-07-01T10:04:36.950452Z","steps":["trace[895287314] 'process raft request' (duration: 260.375856ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:04:36.950507Z","caller":"traceutil/trace.go:171","msg":"trace[504879272] linearizableReadLoop","detail":"{readStateIndex:2455; appliedIndex:2455; }","duration":"111.942816ms","start":"2026-07-01T10:04:36.838523Z","end":"2026-07-01T10:04:36.950466Z","steps":["trace[504879272] 'read index received' (duration: 111.928655ms)","trace[504879272] 'applied index is now lower than readState.Index' (duration: 11.911µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:04:36.950845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.236665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T10:04:36.950917Z","caller":"traceutil/trace.go:171","msg":"trace[574455691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2357; }","duration":"112.40749ms","start":"2026-07-01T10:04:36.838489Z","end":"2026-07-01T10:04:36.950896Z","steps":["trace[574455691] 'agreement among raft nodes before linearized reading' (duration: 112.099761ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:04:37.072375Z","caller":"traceutil/trace.go:171","msg":"trace[1507194119] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"117.032266ms","start":"2026-07-01T10:04:36.955312Z","end":"2026-07-01T10:04:37.072345Z","steps":["trace[1507194119] 'process raft request' (duration: 116.878452ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:04:40.723727Z","caller":"traceutil/trace.go:171","msg":"trace[2082296685] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"216.803806ms","start":"2026-07-01T10:04:40.506899Z","end":"2026-07-01T10:04:40.723703Z","steps":["trace[2082296685] 'process raft request' (duration: 216.674352ms)"],"step_count":1}