{"level":"warn","ts":"2026-05-19T18:50:01.479567Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T18:50:01.479659Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-05-19T18:50:01.47973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T18:50:01.479739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-19T18:50:01.479756Z","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-05-19T18:50:01.48041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-19T18:50:01.480483Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-05-19T18:50:01.485792Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.071277ms"} {"level":"info","ts":"2026-05-19T18:50:01.493191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-19T18:50:01.493292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:50:01.493321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-19T18:50:01.493354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:50:01.493371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-19T18:50:01.493428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-19T18:50:01.499714Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:50:01.500976Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:50:01.501935Z","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-05-19T18:50:01.50403Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:50:01.504161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:50:01.504653Z","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-05-19T18:50:01.504703Z","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-05-19T18:50:01.50473Z","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-05-19T18:50:01.505164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-19T18:50:01.505304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-19T18:50:01.508455Z","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-05-19T18:50:01.508557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-19T18:50:01.508613Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-19T18:50:01.508714Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:50:01.508755Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:50:02.193659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:50:02.19374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:50:02.193773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-19T18:50:02.193793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:50:02.193805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-19T18:50:02.19382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-19T18:50:02.19384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-19T18:50:02.194984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:50:02.195179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:50:02.195203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:50:02.195374Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:50:02.195819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:50:02.195849Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:50:02.196355Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:50:02.196458Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:50:02.19649Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:50:02.198435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-19T18:50:02.199115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T18:50:04.638142Z","caller":"traceutil/trace.go:171","msg":"trace[2122752150] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"226.405289ms","start":"2026-05-19T18:50:04.411709Z","end":"2026-05-19T18:50:04.638114Z","steps":["trace[2122752150] 'read index received' (duration: 226.173094ms)","trace[2122752150] 'applied index is now lower than readState.Index' (duration: 226.355µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:50:04.638288Z","caller":"traceutil/trace.go:171","msg":"trace[1676173540] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"226.759967ms","start":"2026-05-19T18:50:04.411484Z","end":"2026-05-19T18:50:04.638244Z","steps":["trace[1676173540] 'process raft request' (duration: 226.481171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:04.638413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.686206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:50:04.638507Z","caller":"traceutil/trace.go:171","msg":"trace[434762380] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"226.789068ms","start":"2026-05-19T18:50:04.411696Z","end":"2026-05-19T18:50:04.638485Z","steps":["trace[434762380] 'agreement among raft nodes before linearized reading' (duration: 226.593794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:04.638409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.815801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:50:04.638742Z","caller":"traceutil/trace.go:171","msg":"trace[1360297323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"124.190299ms","start":"2026-05-19T18:50:04.514516Z","end":"2026-05-19T18:50:04.638706Z","steps":["trace[1360297323] 'agreement among raft nodes before linearized reading' (duration: 123.79021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:05.145867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899350015546842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:50:05.544808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.793894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T18:50:05.544937Z","caller":"traceutil/trace.go:171","msg":"trace[56659034] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"901.066412ms","start":"2026-05-19T18:50:04.643847Z","end":"2026-05-19T18:50:05.544913Z","steps":["trace[56659034] 'process raft request' (duration: 593.424169ms)","trace[56659034] 'compare' (duration: 306.612929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:50:05.545091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:04.643832Z","time spent":"901.154895ms","remote":"127.0.0.1:52284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:50:05.603277Z","caller":"traceutil/trace.go:171","msg":"trace[1596069312] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"958.411001ms","start":"2026-05-19T18:50:04.644834Z","end":"2026-05-19T18:50:05.603245Z","steps":["trace[1596069312] 'read index received' (duration: 592.540738ms)","trace[1596069312] 'applied index is now lower than readState.Index' (duration: 365.868133ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:50:05.60338Z","caller":"traceutil/trace.go:171","msg":"trace[1199850440] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"837.568159ms","start":"2026-05-19T18:50:04.76576Z","end":"2026-05-19T18:50:05.603328Z","steps":["trace[1199850440] 'process raft request' (duration: 837.333654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:05.603565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:04.76574Z","time spent":"837.725202ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:50:05.603578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.718458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:50:05.603666Z","caller":"traceutil/trace.go:171","msg":"trace[613962931] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:74; }","duration":"958.83289ms","start":"2026-05-19T18:50:04.644818Z","end":"2026-05-19T18:50:05.60365Z","steps":["trace[613962931] 'agreement among raft nodes before linearized reading' (duration: 958.587005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:05.603712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:04.64481Z","time spent":"958.891822ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-05-19T18:50:33.06381Z","caller":"traceutil/trace.go:171","msg":"trace[132157980] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"236.462849ms","start":"2026-05-19T18:50:32.827326Z","end":"2026-05-19T18:50:33.063789Z","steps":["trace[132157980] 'process raft request' (duration: 236.329026ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.07497Z","caller":"traceutil/trace.go:171","msg":"trace[376362456] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"245.318819ms","start":"2026-05-19T18:50:32.829617Z","end":"2026-05-19T18:50:33.074936Z","steps":["trace[376362456] 'process raft request' (duration: 245.154375ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.075021Z","caller":"traceutil/trace.go:171","msg":"trace[1487683166] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"230.229571ms","start":"2026-05-19T18:50:32.844772Z","end":"2026-05-19T18:50:33.075002Z","steps":["trace[1487683166] 'process raft request' (duration: 230.126178ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.075033Z","caller":"traceutil/trace.go:171","msg":"trace[1302650859] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"245.297009ms","start":"2026-05-19T18:50:32.829669Z","end":"2026-05-19T18:50:33.074966Z","steps":["trace[1302650859] 'process raft request' (duration: 245.171536ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.075115Z","caller":"traceutil/trace.go:171","msg":"trace[360582028] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"239.091772ms","start":"2026-05-19T18:50:32.835991Z","end":"2026-05-19T18:50:33.075083Z","steps":["trace[360582028] 'process raft request' (duration: 238.877997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.07512Z","caller":"traceutil/trace.go:171","msg":"trace[670169402] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"230.217301ms","start":"2026-05-19T18:50:32.844838Z","end":"2026-05-19T18:50:33.075055Z","steps":["trace[670169402] 'process raft request' (duration: 230.086628ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.075119Z","caller":"traceutil/trace.go:171","msg":"trace[661618260] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"230.19188ms","start":"2026-05-19T18:50:32.844905Z","end":"2026-05-19T18:50:33.075097Z","steps":["trace[661618260] 'process raft request' (duration: 230.049427ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.075369Z","caller":"traceutil/trace.go:171","msg":"trace[514138449] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"230.096458ms","start":"2026-05-19T18:50:32.845234Z","end":"2026-05-19T18:50:33.075331Z","steps":["trace[514138449] 'process raft request' (duration: 229.75024ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.076069Z","caller":"traceutil/trace.go:171","msg":"trace[869946829] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"224.856634ms","start":"2026-05-19T18:50:32.851193Z","end":"2026-05-19T18:50:33.07605Z","steps":["trace[869946829] 'process raft request' (duration: 224.096356ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.076254Z","caller":"traceutil/trace.go:171","msg":"trace[265415830] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"184.7025ms","start":"2026-05-19T18:50:32.891543Z","end":"2026-05-19T18:50:33.076245Z","steps":["trace[265415830] 'process raft request' (duration: 184.471144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:33.076304Z","caller":"traceutil/trace.go:171","msg":"trace[1987178720] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"210.314168ms","start":"2026-05-19T18:50:32.865976Z","end":"2026-05-19T18:50:33.07629Z","steps":["trace[1987178720] 'process raft request' (duration: 209.95934ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:39.331078Z","caller":"traceutil/trace.go:171","msg":"trace[1201279796] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"123.351651ms","start":"2026-05-19T18:50:39.207688Z","end":"2026-05-19T18:50:39.331039Z","steps":["trace[1201279796] 'read index received' (duration: 53.986293ms)","trace[1201279796] 'applied index is now lower than readState.Index' (duration: 69.364218ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:50:39.331129Z","caller":"traceutil/trace.go:171","msg":"trace[1643429476] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"125.223246ms","start":"2026-05-19T18:50:39.205868Z","end":"2026-05-19T18:50:39.331092Z","steps":["trace[1643429476] 'process raft request' (duration: 55.94104ms)","trace[1643429476] 'compare' (duration: 69.06256ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:50:39.331322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.618388ms","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-05-19T18:50:39.331647Z","caller":"traceutil/trace.go:171","msg":"trace[449485648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"123.991167ms","start":"2026-05-19T18:50:39.207641Z","end":"2026-05-19T18:50:39.331632Z","steps":["trace[449485648] 'agreement among raft nodes before linearized reading' (duration: 123.560357ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:40.093748Z","caller":"traceutil/trace.go:171","msg":"trace[431175778] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"677.950381ms","start":"2026-05-19T18:50:39.415768Z","end":"2026-05-19T18:50:40.093718Z","steps":["trace[431175778] 'process raft request' (duration: 677.09511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:40.094297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:39.415737Z","time spent":"678.092154ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:50:40.298082Z","caller":"traceutil/trace.go:171","msg":"trace[400920365] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"498.450115ms","start":"2026-05-19T18:50:39.799608Z","end":"2026-05-19T18:50:40.298058Z","steps":["trace[400920365] 'read index received' (duration: 292.725366ms)","trace[400920365] 'applied index is now lower than readState.Index' (duration: 205.723869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:50:40.298233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.875418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:50:40.298275Z","caller":"traceutil/trace.go:171","msg":"trace[330251773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"286.96547ms","start":"2026-05-19T18:50:40.011297Z","end":"2026-05-19T18:50:40.298262Z","steps":["trace[330251773] 'agreement among raft nodes before linearized reading' (duration: 286.888308ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:50:40.298249Z","caller":"traceutil/trace.go:171","msg":"trace[255713705] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"780.339174ms","start":"2026-05-19T18:50:39.517818Z","end":"2026-05-19T18:50:40.298157Z","steps":["trace[255713705] 'process raft request' (duration: 779.71268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:40.298434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.804664ms","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-05-19T18:50:40.298594Z","caller":"traceutil/trace.go:171","msg":"trace[1014433132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"498.938016ms","start":"2026-05-19T18:50:39.799559Z","end":"2026-05-19T18:50:40.298497Z","steps":["trace[1014433132] 'agreement among raft nodes before linearized reading' (duration: 498.616659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:40.298674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:39.799541Z","time spent":"499.120131ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T18:50:40.298688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:39.517543Z","time spent":"780.782544ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:50:41.26627Z","caller":"traceutil/trace.go:171","msg":"trace[357063391] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"256.063374ms","start":"2026-05-19T18:50:41.01018Z","end":"2026-05-19T18:50:41.266244Z","steps":["trace[357063391] 'read index received' (duration: 255.89302ms)","trace[357063391] 'applied index is now lower than readState.Index' (duration: 169.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:50:41.266337Z","caller":"traceutil/trace.go:171","msg":"trace[1601935437] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"911.799317ms","start":"2026-05-19T18:50:40.354497Z","end":"2026-05-19T18:50:41.266296Z","steps":["trace[1601935437] 'process raft request' (duration: 911.548212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:41.266486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.28331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:50:41.266533Z","caller":"traceutil/trace.go:171","msg":"trace[1454081289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"256.368742ms","start":"2026-05-19T18:50:41.010151Z","end":"2026-05-19T18:50:41.266519Z","steps":["trace[1454081289] 'agreement among raft nodes before linearized reading' (duration: 256.260769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:50:41.266578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:50:40.354473Z","time spent":"912.014383ms","remote":"127.0.0.1:52152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:50:41.266619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.338679ms","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-05-19T18:50:41.267157Z","caller":"traceutil/trace.go:171","msg":"trace[1983309663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"136.894064ms","start":"2026-05-19T18:50:41.130219Z","end":"2026-05-19T18:50:41.267113Z","steps":["trace[1983309663] 'agreement among raft nodes before linearized reading' (duration: 136.260218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:29.936973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.709262ms","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-05-19T18:51:29.937068Z","caller":"traceutil/trace.go:171","msg":"trace[1885322001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"378.829465ms","start":"2026-05-19T18:51:29.558222Z","end":"2026-05-19T18:51:29.937051Z","steps":["trace[1885322001] 'range keys from in-memory index tree' (duration: 378.58206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:29.937107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:51:29.558214Z","time spent":"378.883747ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:51:29.937411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.80593ms","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-05-19T18:51:29.937493Z","caller":"traceutil/trace.go:171","msg":"trace[532000440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"374.918302ms","start":"2026-05-19T18:51:29.562557Z","end":"2026-05-19T18:51:29.937475Z","steps":["trace[532000440] 'range keys from in-memory index tree' (duration: 374.648786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:29.937534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:51:29.562547Z","time spent":"374.978533ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T18:51:29.938014Z","caller":"traceutil/trace.go:171","msg":"trace[1999142434] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"259.940834ms","start":"2026-05-19T18:51:29.678058Z","end":"2026-05-19T18:51:29.937999Z","steps":["trace[1999142434] 'process raft request' (duration: 259.827721ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:51:50.303742Z","caller":"traceutil/trace.go:171","msg":"trace[1919804230] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"253.501912ms","start":"2026-05-19T18:51:50.050214Z","end":"2026-05-19T18:51:50.303716Z","steps":["trace[1919804230] 'read index received' (duration: 253.251996ms)","trace[1919804230] 'applied index is now lower than readState.Index' (duration: 248.446µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:51:50.304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.35822ms","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-05-19T18:51:50.304041Z","caller":"traceutil/trace.go:171","msg":"trace[1385661300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"250.466442ms","start":"2026-05-19T18:51:50.053564Z","end":"2026-05-19T18:51:50.304031Z","steps":["trace[1385661300] 'agreement among raft nodes before linearized reading' (duration: 250.304968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:50.304036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.753489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T18:51:50.304162Z","caller":"traceutil/trace.go:171","msg":"trace[1029800260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"253.969154ms","start":"2026-05-19T18:51:50.05018Z","end":"2026-05-19T18:51:50.304149Z","steps":["trace[1029800260] 'agreement among raft nodes before linearized reading' (duration: 253.642066ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:51:50.304371Z","caller":"traceutil/trace.go:171","msg":"trace[1548100982] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"294.427257ms","start":"2026-05-19T18:51:50.009344Z","end":"2026-05-19T18:51:50.303771Z","steps":["trace[1548100982] 'process raft request' (duration: 294.205932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:50.528607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.013595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:51:50.528683Z","caller":"traceutil/trace.go:171","msg":"trace[203276623] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"209.528987ms","start":"2026-05-19T18:51:50.319142Z","end":"2026-05-19T18:51:50.528671Z","steps":["trace[203276623] 'read index received' (duration: 26.080054ms)","trace[203276623] 'applied index is now lower than readState.Index' (duration: 183.447983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:51:50.528729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.584148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:51:50.528749Z","caller":"traceutil/trace.go:171","msg":"trace[1146469992] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1071; }","duration":"209.622038ms","start":"2026-05-19T18:51:50.31912Z","end":"2026-05-19T18:51:50.528742Z","steps":["trace[1146469992] 'agreement among raft nodes before linearized reading' (duration: 209.581908ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:51:50.528782Z","caller":"traceutil/trace.go:171","msg":"trace[2060314357] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"219.82575ms","start":"2026-05-19T18:51:50.308924Z","end":"2026-05-19T18:51:50.528749Z","steps":["trace[2060314357] 'process raft request' (duration: 111.576589ms)","trace[2060314357] 'compare' (duration: 107.900153ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:51:51.107674Z","caller":"traceutil/trace.go:171","msg":"trace[1876147789] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"100.020226ms","start":"2026-05-19T18:51:51.007634Z","end":"2026-05-19T18:51:51.107654Z","steps":["trace[1876147789] 'read index received' (duration: 99.851712ms)","trace[1876147789] 'applied index is now lower than readState.Index' (duration: 167.704µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:51:51.107804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.15895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:51:51.10787Z","caller":"traceutil/trace.go:171","msg":"trace[524100974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"100.236091ms","start":"2026-05-19T18:51:51.007589Z","end":"2026-05-19T18:51:51.107825Z","steps":["trace[524100974] 'agreement among raft nodes before linearized reading' (duration: 100.151349ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:51:51.107962Z","caller":"traceutil/trace.go:171","msg":"trace[335012493] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"185.824169ms","start":"2026-05-19T18:51:50.922105Z","end":"2026-05-19T18:51:51.107929Z","steps":["trace[335012493] 'process raft request' (duration: 185.422709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:51.815832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.04093ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-19T18:51:52.927287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.211856ms","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-05-19T18:51:52.927362Z","caller":"traceutil/trace.go:171","msg":"trace[956678044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"183.300138ms","start":"2026-05-19T18:51:52.744046Z","end":"2026-05-19T18:51:52.927346Z","steps":["trace[956678044] 'range keys from in-memory index tree' (duration: 183.046672ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:51:53.322463Z","caller":"traceutil/trace.go:171","msg":"trace[456273349] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"391.196677ms","start":"2026-05-19T18:51:52.931243Z","end":"2026-05-19T18:51:53.32244Z","steps":["trace[456273349] 'process raft request' (duration: 391.033743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:53.322639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:51:52.931221Z","time spent":"391.31155ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:51:53.497121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.771334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:51:53.49724Z","caller":"traceutil/trace.go:171","msg":"trace[1668843405] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"487.162057ms","start":"2026-05-19T18:51:53.010058Z","end":"2026-05-19T18:51:53.49722Z","steps":["trace[1668843405] 'read index received' (duration: 312.462292ms)","trace[1668843405] 'applied index is now lower than readState.Index' (duration: 174.698295ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:51:53.497294Z","caller":"traceutil/trace.go:171","msg":"trace[108369942] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"565.257707ms","start":"2026-05-19T18:51:52.931999Z","end":"2026-05-19T18:51:53.497256Z","steps":["trace[108369942] 'process raft request' (duration: 439.259359ms)","trace[108369942] 'compare' (duration: 125.524097ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:51:53.497334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.27123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:51:53.497475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:51:52.931976Z","time spent":"565.41343ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:51:53.497517Z","caller":"traceutil/trace.go:171","msg":"trace[1626985968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"487.474927ms","start":"2026-05-19T18:51:53.010011Z","end":"2026-05-19T18:51:53.497486Z","steps":["trace[1626985968] 'agreement among raft nodes before linearized reading' (duration: 487.272031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:51:53.497603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:51:53.009993Z","time spent":"487.578499ms","remote":"127.0.0.1:51864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:52:05.897322Z","caller":"traceutil/trace.go:171","msg":"trace[111025868] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"346.812331ms","start":"2026-05-19T18:52:05.550484Z","end":"2026-05-19T18:52:05.897296Z","steps":["trace[111025868] 'read index received' (duration: 346.698208ms)","trace[111025868] 'applied index is now lower than readState.Index' (duration: 113.153µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:52:05.897394Z","caller":"traceutil/trace.go:171","msg":"trace[1377447767] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"523.545195ms","start":"2026-05-19T18:52:05.373804Z","end":"2026-05-19T18:52:05.897349Z","steps":["trace[1377447767] 'process raft request' (duration: 523.30874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:05.897559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.061627ms","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-05-19T18:52:05.897595Z","caller":"traceutil/trace.go:171","msg":"trace[748963986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1169; }","duration":"347.15624ms","start":"2026-05-19T18:52:05.550428Z","end":"2026-05-19T18:52:05.897585Z","steps":["trace[748963986] 'agreement among raft nodes before linearized reading' (duration: 347.004316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:05.897587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:05.373787Z","time spent":"523.691459ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:52:05.897634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:05.550402Z","time spent":"347.223261ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T18:52:05.897745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.653115ms","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-05-19T18:52:05.89782Z","caller":"traceutil/trace.go:171","msg":"trace[1124256782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1169; }","duration":"160.815599ms","start":"2026-05-19T18:52:05.736983Z","end":"2026-05-19T18:52:05.897799Z","steps":["trace[1124256782] 'agreement among raft nodes before linearized reading' (duration: 160.619694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:52:06.216824Z","caller":"traceutil/trace.go:171","msg":"trace[79591915] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"314.850858ms","start":"2026-05-19T18:52:05.901953Z","end":"2026-05-19T18:52:06.216804Z","steps":["trace[79591915] 'process raft request' (duration: 314.755065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:06.216959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:05.901931Z","time spent":"314.947421ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:52:06.218886Z","caller":"traceutil/trace.go:171","msg":"trace[1441719136] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"209.098486ms","start":"2026-05-19T18:52:06.009764Z","end":"2026-05-19T18:52:06.218862Z","steps":["trace[1441719136] 'read index received' (duration: 207.018287ms)","trace[1441719136] 'applied index is now lower than readState.Index' (duration: 2.079549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:52:06.218975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.20403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:52:06.21895Z","caller":"traceutil/trace.go:171","msg":"trace[1536665197] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"316.793274ms","start":"2026-05-19T18:52:05.902136Z","end":"2026-05-19T18:52:06.218929Z","steps":["trace[1536665197] 'process raft request' (duration: 316.596589ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:52:06.219Z","caller":"traceutil/trace.go:171","msg":"trace[162834723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"209.286122ms","start":"2026-05-19T18:52:06.009707Z","end":"2026-05-19T18:52:06.218993Z","steps":["trace[162834723] 'agreement among raft nodes before linearized reading' (duration: 209.23229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:06.219136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:05.902117Z","time spent":"316.900966ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:52:18.790506Z","caller":"traceutil/trace.go:171","msg":"trace[1434533318] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"123.767006ms","start":"2026-05-19T18:52:18.666711Z","end":"2026-05-19T18:52:18.790478Z","steps":["trace[1434533318] 'process raft request' (duration: 123.543261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:22.135125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.777486ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T18:52:22.13627Z","caller":"traceutil/trace.go:171","msg":"trace[1055353382] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"167.844605ms","start":"2026-05-19T18:52:21.968407Z","end":"2026-05-19T18:52:22.136251Z","steps":["trace[1055353382] 'read index received' (duration: 54.959325ms)","trace[1055353382] 'applied index is now lower than readState.Index' (duration: 112.88398ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:52:22.136338Z","caller":"traceutil/trace.go:171","msg":"trace[1512903394] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"270.144915ms","start":"2026-05-19T18:52:21.866162Z","end":"2026-05-19T18:52:22.136307Z","steps":["trace[1512903394] 'process raft request' (duration: 269.059509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:22.309522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.106216ms","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-05-19T18:52:22.309627Z","caller":"traceutil/trace.go:171","msg":"trace[1976009428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"341.25949ms","start":"2026-05-19T18:52:21.968347Z","end":"2026-05-19T18:52:22.309606Z","steps":["trace[1976009428] 'agreement among raft nodes before linearized reading' (duration: 168.008729ms)","trace[1976009428] 'range keys from in-memory index tree' (duration: 173.081527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:52:22.309666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:21.968325Z","time spent":"341.331352ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T18:52:22.309809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.920275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:52:22.30984Z","caller":"traceutil/trace.go:171","msg":"trace[1907928011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"293.989717ms","start":"2026-05-19T18:52:22.01584Z","end":"2026-05-19T18:52:22.30983Z","steps":["trace[1907928011] 'agreement among raft nodes before linearized reading' (duration: 120.53403ms)","trace[1907928011] 'range keys from in-memory index tree' (duration: 173.420586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:52:23.131364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.660987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:52:23.131582Z","caller":"traceutil/trace.go:171","msg":"trace[283071784] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"268.155127ms","start":"2026-05-19T18:52:22.863406Z","end":"2026-05-19T18:52:23.131561Z","steps":["trace[283071784] 'process raft request' (duration: 268.050694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:52:23.131757Z","caller":"traceutil/trace.go:171","msg":"trace[924075283] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"273.118322ms","start":"2026-05-19T18:52:22.858606Z","end":"2026-05-19T18:52:23.131725Z","steps":["trace[924075283] 'process raft request' (duration: 101.026015ms)","trace[924075283] 'compare' (duration: 171.541945ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:52:23.709375Z","caller":"traceutil/trace.go:171","msg":"trace[220705556] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"571.66405ms","start":"2026-05-19T18:52:23.137688Z","end":"2026-05-19T18:52:23.709352Z","steps":["trace[220705556] 'process raft request' (duration: 571.513886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:23.709538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:23.137677Z","time spent":"571.792712ms","remote":"127.0.0.1:51962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:52:23.714665Z","caller":"traceutil/trace.go:171","msg":"trace[465326142] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"573.028502ms","start":"2026-05-19T18:52:23.141612Z","end":"2026-05-19T18:52:23.71464Z","steps":["trace[465326142] 'process raft request' (duration: 572.895369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:23.714848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:23.141593Z","time spent":"573.132224ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:52:36.638794Z","caller":"traceutil/trace.go:171","msg":"trace[423069564] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"110.103188ms","start":"2026-05-19T18:52:36.528625Z","end":"2026-05-19T18:52:36.638728Z","steps":["trace[423069564] 'read index received' (duration: 109.720048ms)","trace[423069564] 'applied index is now lower than readState.Index' (duration: 381.94µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:52:36.638841Z","caller":"traceutil/trace.go:171","msg":"trace[788994483] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"159.127244ms","start":"2026-05-19T18:52:36.479665Z","end":"2026-05-19T18:52:36.638792Z","steps":["trace[788994483] 'process raft request' (duration: 158.817136ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:52:36.638983Z","caller":"traceutil/trace.go:171","msg":"trace[918196496] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"141.557963ms","start":"2026-05-19T18:52:36.49741Z","end":"2026-05-19T18:52:36.638968Z","steps":["trace[918196496] 'process raft request' (duration: 141.261577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:36.639289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.64863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-19T18:52:36.639352Z","caller":"traceutil/trace.go:171","msg":"trace[1795570044] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"110.754903ms","start":"2026-05-19T18:52:36.528576Z","end":"2026-05-19T18:52:36.639331Z","steps":["trace[1795570044] 'agreement among raft nodes before linearized reading' (duration: 110.561568ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:52:37.057037Z","caller":"traceutil/trace.go:171","msg":"trace[1199449802] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"412.992136ms","start":"2026-05-19T18:52:36.643696Z","end":"2026-05-19T18:52:37.056688Z","steps":["trace[1199449802] 'process raft request' (duration: 412.537216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:37.057323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:36.643673Z","time spent":"413.449956ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:52:37.057462Z","caller":"traceutil/trace.go:171","msg":"trace[1182394442] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1393; }","duration":"266.798664ms","start":"2026-05-19T18:52:36.790649Z","end":"2026-05-19T18:52:37.057447Z","steps":["trace[1182394442] 'read index received' (duration: 265.530975ms)","trace[1182394442] 'applied index is now lower than readState.Index' (duration: 1.266989ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:52:37.057612Z","caller":"traceutil/trace.go:171","msg":"trace[2066098324] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"413.377975ms","start":"2026-05-19T18:52:36.644171Z","end":"2026-05-19T18:52:37.057549Z","steps":["trace[2066098324] 'process raft request' (duration: 413.14352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:37.057691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.037079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:52:37.057743Z","caller":"traceutil/trace.go:171","msg":"trace[542913180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1344; }","duration":"267.142262ms","start":"2026-05-19T18:52:36.790588Z","end":"2026-05-19T18:52:37.05773Z","steps":["trace[542913180] 'agreement among raft nodes before linearized reading' (duration: 267.05974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:37.057847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:36.644144Z","time spent":"413.59418ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:52:37.058221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.418049ms","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-05-19T18:52:37.058264Z","caller":"traceutil/trace.go:171","msg":"trace[904041035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"138.503041ms","start":"2026-05-19T18:52:36.919749Z","end":"2026-05-19T18:52:37.058253Z","steps":["trace[904041035] 'agreement among raft nodes before linearized reading' (duration: 138.370618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:37.600433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899350015549554,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:52:37.65653Z","caller":"traceutil/trace.go:171","msg":"trace[756667063] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"593.749496ms","start":"2026-05-19T18:52:37.062747Z","end":"2026-05-19T18:52:37.656496Z","steps":["trace[756667063] 'process raft request' (duration: 546.469419ms)","trace[756667063] 'compare' (duration: 46.843007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:52:37.656761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:37.062726Z","time spent":"593.875399ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:52:37.656798Z","caller":"traceutil/trace.go:171","msg":"trace[1215564186] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"557.247411ms","start":"2026-05-19T18:52:37.099495Z","end":"2026-05-19T18:52:37.656743Z","steps":["trace[1215564186] 'read index received' (duration: 509.618817ms)","trace[1215564186] 'applied index is now lower than readState.Index' (duration: 47.626574ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:52:37.656888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.393385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:52:37.656931Z","caller":"traceutil/trace.go:171","msg":"trace[1456567651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"557.480697ms","start":"2026-05-19T18:52:37.099435Z","end":"2026-05-19T18:52:37.656916Z","steps":["trace[1456567651] 'agreement among raft nodes before linearized reading' (duration: 557.411496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:52:37.657043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:52:37.099333Z","time spent":"557.698062ms","remote":"127.0.0.1:51874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}