{"level":"warn","ts":"2026-05-19T18:26:41.471002Z","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:26:41.471092Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.183: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.19.213.183:2380","--initial-cluster=instance=https://199.19.213.183:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.183:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.183: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:26:41.471269Z","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:26:41.471285Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.183:2380"]} {"level":"info","ts":"2026-05-19T18:26:41.47232Z","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:26:41.473135Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"]} {"level":"info","ts":"2026-05-19T18:26:41.473222Z","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.19.213.183:2380"],"listen-peer-urls":["https://199.19.213.183:2380"],"advertise-client-urls":["https://199.19.213.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.183: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:26:41.479364Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.918964ms"} {"level":"info","ts":"2026-05-19T18:26:41.485529Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d11d60056e8410fb","cluster-id":"524d1f88cdf60208"} {"level":"info","ts":"2026-05-19T18:26:41.485602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:26:41.485677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became follower at term 0"} {"level":"info","ts":"2026-05-19T18:26:41.485699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d11d60056e8410fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:26:41.485753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became follower at term 1"} {"level":"info","ts":"2026-05-19T18:26:41.485784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=(15068305504696799483)"} {"level":"warn","ts":"2026-05-19T18:26:41.493951Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:26:41.495338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:26:41.497813Z","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:26:41.500028Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d11d60056e8410fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:26:41.5007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d11d60056e8410fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:26:41.501111Z","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:26:41.501301Z","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:26:41.50139Z","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:26:41.505713Z","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:26:41.505827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.183:2380"} {"level":"info","ts":"2026-05-19T18:26:41.50587Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.183:2380"} {"level":"info","ts":"2026-05-19T18:26:41.506154Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d11d60056e8410fb","initial-advertise-peer-urls":["https://199.19.213.183:2380"],"listen-peer-urls":["https://199.19.213.183:2380"],"advertise-client-urls":["https://199.19.213.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:26:41.506193Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:26:41.507008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=(15068305504696799483)"} {"level":"info","ts":"2026-05-19T18:26:41.507173Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"524d1f88cdf60208","local-member-id":"d11d60056e8410fb","added-peer-id":"d11d60056e8410fb","added-peer-peer-urls":["https://199.19.213.183:2380"]} {"level":"info","ts":"2026-05-19T18:26:41.687156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:26:41.687209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:26:41.687237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb received MsgPreVoteResp from d11d60056e8410fb at term 1"} {"level":"info","ts":"2026-05-19T18:26:41.687254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:26:41.68728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb received MsgVoteResp from d11d60056e8410fb at term 2"} {"level":"info","ts":"2026-05-19T18:26:41.687301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became leader at term 2"} {"level":"info","ts":"2026-05-19T18:26:41.687314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d11d60056e8410fb elected leader d11d60056e8410fb at term 2"} {"level":"info","ts":"2026-05-19T18:26:41.688413Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d11d60056e8410fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.183:2379]}","request-path":"/0/members/d11d60056e8410fb/attributes","cluster-id":"524d1f88cdf60208","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:26:41.68874Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:26:41.688886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:26:41.689034Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:26:41.689036Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:26:41.689065Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:26:41.689939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"524d1f88cdf60208","local-member-id":"d11d60056e8410fb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:26:41.690029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:26:41.690059Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:26:41.690514Z","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:26:41.691333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.183:2379"} {"level":"info","ts":"2026-05-19T18:26:44.373712Z","caller":"traceutil/trace.go:171","msg":"trace[993718720] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"132.583519ms","start":"2026-05-19T18:26:44.241112Z","end":"2026-05-19T18:26:44.373696Z","steps":["trace[993718720] 'read index received' (duration: 132.362203ms)","trace[993718720] 'applied index is now lower than readState.Index' (duration: 220.786µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:44.373859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.738772ms","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:26:44.373899Z","caller":"traceutil/trace.go:171","msg":"trace[451261371] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"132.847915ms","start":"2026-05-19T18:26:44.241045Z","end":"2026-05-19T18:26:44.373893Z","steps":["trace[451261371] 'agreement among raft nodes before linearized reading' (duration: 132.754623ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:44.373801Z","caller":"traceutil/trace.go:171","msg":"trace[1353521617] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"133.85991ms","start":"2026-05-19T18:26:44.239904Z","end":"2026-05-19T18:26:44.373764Z","steps":["trace[1353521617] 'process raft request' (duration: 133.662786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:45.201985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.735199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-19T18:26:45.202139Z","caller":"traceutil/trace.go:171","msg":"trace[51520205] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"823.000418ms","start":"2026-05-19T18:26:44.379103Z","end":"2026-05-19T18:26:45.202104Z","steps":["trace[51520205] 'read index received' (duration: 250.252114ms)","trace[51520205] 'applied index is now lower than readState.Index' (duration: 572.746624ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:45.202202Z","caller":"traceutil/trace.go:171","msg":"trace[1980416360] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"824.010564ms","start":"2026-05-19T18:26:44.378159Z","end":"2026-05-19T18:26:45.20217Z","steps":["trace[1980416360] 'process raft request' (duration: 251.383013ms)","trace[1980416360] 'compare' (duration: 571.583305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:45.202297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.183163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:26:45.20233Z","caller":"traceutil/trace.go:171","msg":"trace[225374255] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:73; }","duration":"823.228834ms","start":"2026-05-19T18:26:44.379092Z","end":"2026-05-19T18:26:45.202321Z","steps":["trace[225374255] 'agreement among raft nodes before linearized reading' (duration: 823.136941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:45.202354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:44.379088Z","time spent":"823.260235ms","remote":"127.0.0.1:34930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-05-19T18:26:45.202389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:44.378136Z","time spent":"824.164017ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:26:45.537376Z","caller":"traceutil/trace.go:171","msg":"trace[213875662] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"332.630264ms","start":"2026-05-19T18:26:45.204719Z","end":"2026-05-19T18:26:45.53735Z","steps":["trace[213875662] 'read index received' (duration: 317.140219ms)","trace[213875662] 'applied index is now lower than readState.Index' (duration: 15.488865ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:45.537533Z","caller":"traceutil/trace.go:171","msg":"trace[598766280] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"536.629206ms","start":"2026-05-19T18:26:45.00086Z","end":"2026-05-19T18:26:45.53749Z","steps":["trace[598766280] 'process raft request' (duration: 521.075869ms)","trace[598766280] 'compare' (duration: 15.26531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:45.537601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.854481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:26:45.537672Z","caller":"traceutil/trace.go:171","msg":"trace[684415638] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:74; }","duration":"332.981423ms","start":"2026-05-19T18:26:45.204678Z","end":"2026-05-19T18:26:45.537659Z","steps":["trace[684415638] 'agreement among raft nodes before linearized reading' (duration: 332.813869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:45.53771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:45.204662Z","time spent":"333.038985ms","remote":"127.0.0.1:34930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-05-19T18:26:45.537761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:45.000821Z","time spent":"536.822ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:27:13.304847Z","caller":"traceutil/trace.go:171","msg":"trace[2112363150] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"128.293326ms","start":"2026-05-19T18:27:13.176534Z","end":"2026-05-19T18:27:13.304827Z","steps":["trace[2112363150] 'process raft request' (duration: 128.194564ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:13.320524Z","caller":"traceutil/trace.go:171","msg":"trace[1100039062] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"109.505975ms","start":"2026-05-19T18:27:13.210996Z","end":"2026-05-19T18:27:13.320502Z","steps":["trace[1100039062] 'process raft request' (duration: 109.235299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:13.320663Z","caller":"traceutil/trace.go:171","msg":"trace[783782382] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"135.919822ms","start":"2026-05-19T18:27:13.184737Z","end":"2026-05-19T18:27:13.320656Z","steps":["trace[783782382] 'process raft request' (duration: 130.919077ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:13.320725Z","caller":"traceutil/trace.go:171","msg":"trace[1615700241] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"130.805565ms","start":"2026-05-19T18:27:13.189913Z","end":"2026-05-19T18:27:13.320718Z","steps":["trace[1615700241] 'process raft request' (duration: 130.288343ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:13.320839Z","caller":"traceutil/trace.go:171","msg":"trace[791877124] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"102.248318ms","start":"2026-05-19T18:27:13.218573Z","end":"2026-05-19T18:27:13.320821Z","steps":["trace[791877124] 'process raft request' (duration: 101.697946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.573459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.719429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:27:14.573596Z","caller":"traceutil/trace.go:171","msg":"trace[111672684] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"848.703886ms","start":"2026-05-19T18:27:13.724861Z","end":"2026-05-19T18:27:14.573565Z","steps":["trace[111672684] 'process raft request' (duration: 432.66765ms)","trace[111672684] 'compare' (duration: 415.494794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:14.573701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:13.724837Z","time spent":"848.826788ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:14.586398Z","caller":"traceutil/trace.go:171","msg":"trace[46168761] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"827.657662ms","start":"2026-05-19T18:27:13.758702Z","end":"2026-05-19T18:27:14.586359Z","steps":["trace[46168761] 'read index received' (duration: 398.798061ms)","trace[46168761] 'applied index is now lower than readState.Index' (duration: 428.858791ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:14.586554Z","caller":"traceutil/trace.go:171","msg":"trace[823411383] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"843.478446ms","start":"2026-05-19T18:27:13.743067Z","end":"2026-05-19T18:27:14.586545Z","steps":["trace[823411383] 'process raft request' (duration: 843.100137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.586613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:13.743036Z","time spent":"843.542217ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:27:14.58669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.9812ms","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-05-19T18:27:14.58673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.46362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:14.586739Z","caller":"traceutil/trace.go:171","msg":"trace[1309524388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"828.058442ms","start":"2026-05-19T18:27:13.758669Z","end":"2026-05-19T18:27:14.586727Z","steps":["trace[1309524388] 'agreement among raft nodes before linearized reading' (duration: 827.888648ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:14.586755Z","caller":"traceutil/trace.go:171","msg":"trace[1734342404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"676.523761ms","start":"2026-05-19T18:27:13.910224Z","end":"2026-05-19T18:27:14.586748Z","steps":["trace[1734342404] 'agreement among raft nodes before linearized reading' (duration: 676.48926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.586772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:13.758655Z","time spent":"828.108292ms","remote":"127.0.0.1:34846","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:27:14.586813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.939257ms","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-05-19T18:27:14.586836Z","caller":"traceutil/trace.go:171","msg":"trace[838771957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"299.010799ms","start":"2026-05-19T18:27:14.28782Z","end":"2026-05-19T18:27:14.586831Z","steps":["trace[838771957] 'agreement among raft nodes before linearized reading' (duration: 298.969138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:14.5869Z","caller":"traceutil/trace.go:171","msg":"trace[1774783335] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"513.093296ms","start":"2026-05-19T18:27:14.073796Z","end":"2026-05-19T18:27:14.58689Z","steps":["trace[1774783335] 'process raft request' (duration: 512.519753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.586946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.200679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-19T18:27:14.586965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:14.073773Z","time spent":"513.157547ms","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:14.586977Z","caller":"traceutil/trace.go:171","msg":"trace[1375956539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"345.273661ms","start":"2026-05-19T18:27:14.241695Z","end":"2026-05-19T18:27:14.586969Z","steps":["trace[1375956539] 'agreement among raft nodes before linearized reading' (duration: 345.22492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.587001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:14.241668Z","time spent":"345.326852ms","remote":"127.0.0.1:34846","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-05-19T18:27:14.587072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.627492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T18:27:14.587103Z","caller":"traceutil/trace.go:171","msg":"trace[450703990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"383.697164ms","start":"2026-05-19T18:27:14.2034Z","end":"2026-05-19T18:27:14.587097Z","steps":["trace[450703990] 'agreement among raft nodes before linearized reading' (duration: 383.652683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.587119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:14.203379Z","time spent":"383.736505ms","remote":"127.0.0.1:34846","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-05-19T18:27:14.586776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:13.910202Z","time spent":"676.569482ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:27:20.282311Z","caller":"traceutil/trace.go:171","msg":"trace[465149522] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"106.676811ms","start":"2026-05-19T18:27:20.175609Z","end":"2026-05-19T18:27:20.282286Z","steps":["trace[465149522] 'process raft request' (duration: 106.086578ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:21.066858Z","caller":"traceutil/trace.go:171","msg":"trace[699221589] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"749.581699ms","start":"2026-05-19T18:27:20.317251Z","end":"2026-05-19T18:27:21.066833Z","steps":["trace[699221589] 'process raft request' (duration: 749.331133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:21.067047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.317237Z","time spent":"749.725212ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:21.092264Z","caller":"traceutil/trace.go:171","msg":"trace[1331947329] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"465.109284ms","start":"2026-05-19T18:27:20.627114Z","end":"2026-05-19T18:27:21.092223Z","steps":["trace[1331947329] 'read index received' (duration: 439.543577ms)","trace[1331947329] 'applied index is now lower than readState.Index' (duration: 25.564607ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:21.092364Z","caller":"traceutil/trace.go:171","msg":"trace[1195012675] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"751.136695ms","start":"2026-05-19T18:27:20.341196Z","end":"2026-05-19T18:27:21.092333Z","steps":["trace[1195012675] 'process raft request' (duration: 738.400393ms)","trace[1195012675] 'compare' (duration: 12.507246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:21.093119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.341172Z","time spent":"751.912262ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:27:21.092679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.558014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T18:27:21.092852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.804503ms","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:27:21.093453Z","caller":"traceutil/trace.go:171","msg":"trace[1661262131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"457.441739ms","start":"2026-05-19T18:27:20.636Z","end":"2026-05-19T18:27:21.093442Z","steps":["trace[1661262131] 'agreement among raft nodes before linearized reading' (duration: 456.813343ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:21.093415Z","caller":"traceutil/trace.go:171","msg":"trace[1592417514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"466.331353ms","start":"2026-05-19T18:27:20.627067Z","end":"2026-05-19T18:27:21.093399Z","steps":["trace[1592417514] 'agreement among raft nodes before linearized reading' (duration: 465.333709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:21.093541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.627045Z","time spent":"466.486047ms","remote":"127.0.0.1:34846","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":"warn","ts":"2026-05-19T18:27:21.092948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.052848ms","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-05-19T18:27:21.093648Z","caller":"traceutil/trace.go:171","msg":"trace[2106010614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"450.775045ms","start":"2026-05-19T18:27:20.642864Z","end":"2026-05-19T18:27:21.093639Z","steps":["trace[2106010614] 'agreement among raft nodes before linearized reading' (duration: 449.994156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:21.093678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.642852Z","time spent":"450.818016ms","remote":"127.0.0.1:34846","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:27:21.093015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.131529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-19T18:27:21.093487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.635979Z","time spent":"457.49829ms","remote":"127.0.0.1:34846","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-05-19T18:27:21.093794Z","caller":"traceutil/trace.go:171","msg":"trace[292479921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"450.910538ms","start":"2026-05-19T18:27:20.642845Z","end":"2026-05-19T18:27:21.093756Z","steps":["trace[292479921] 'agreement among raft nodes before linearized reading' (duration: 450.087088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:21.093885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:20.642835Z","time spent":"451.018151ms","remote":"127.0.0.1:34846","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":"info","ts":"2026-05-19T18:27:33.314747Z","caller":"traceutil/trace.go:171","msg":"trace[1243672258] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"157.187501ms","start":"2026-05-19T18:27:33.157538Z","end":"2026-05-19T18:27:33.314726Z","steps":["trace[1243672258] 'process raft request' (duration: 157.065468ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.317171Z","caller":"traceutil/trace.go:171","msg":"trace[1953971278] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"156.689329ms","start":"2026-05-19T18:27:33.160463Z","end":"2026-05-19T18:27:33.317152Z","steps":["trace[1953971278] 'process raft request' (duration: 156.649408ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.317203Z","caller":"traceutil/trace.go:171","msg":"trace[1687860014] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"156.768361ms","start":"2026-05-19T18:27:33.160425Z","end":"2026-05-19T18:27:33.317193Z","steps":["trace[1687860014] 'process raft request' (duration: 156.662068ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.317425Z","caller":"traceutil/trace.go:171","msg":"trace[756112348] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"158.47714ms","start":"2026-05-19T18:27:33.158934Z","end":"2026-05-19T18:27:33.317411Z","steps":["trace[756112348] 'process raft request' (duration: 158.04309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:36.323513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.254398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:36.323587Z","caller":"traceutil/trace.go:171","msg":"trace[719832904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"593.379801ms","start":"2026-05-19T18:27:35.730191Z","end":"2026-05-19T18:27:36.323571Z","steps":["trace[719832904] 'range keys from in-memory index tree' (duration: 593.114134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:36.323626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:35.730159Z","time spent":"593.459272ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:27:36.324034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.514142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:36.324074Z","caller":"traceutil/trace.go:171","msg":"trace[1140472557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"411.605163ms","start":"2026-05-19T18:27:35.912459Z","end":"2026-05-19T18:27:36.324065Z","steps":["trace[1140472557] 'range keys from in-memory index tree' (duration: 411.41646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:36.324102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:35.912435Z","time spent":"411.660455ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:28:09.804792Z","caller":"traceutil/trace.go:171","msg":"trace[1332203753] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"240.888848ms","start":"2026-05-19T18:28:09.563878Z","end":"2026-05-19T18:28:09.804766Z","steps":["trace[1332203753] 'process raft request' (duration: 240.730455ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:09.80532Z","caller":"traceutil/trace.go:171","msg":"trace[1842478198] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"207.631125ms","start":"2026-05-19T18:28:09.597665Z","end":"2026-05-19T18:28:09.805296Z","steps":["trace[1842478198] 'process raft request' (duration: 207.596164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:09.805475Z","caller":"traceutil/trace.go:171","msg":"trace[1023532519] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"241.238026ms","start":"2026-05-19T18:28:09.564227Z","end":"2026-05-19T18:28:09.805465Z","steps":["trace[1023532519] 'process raft request' (duration: 240.999231ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:09.805389Z","caller":"traceutil/trace.go:171","msg":"trace[183727823] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"241.212036ms","start":"2026-05-19T18:28:09.564146Z","end":"2026-05-19T18:28:09.805358Z","steps":["trace[183727823] 'process raft request' (duration: 240.999691ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:09.940945Z","caller":"traceutil/trace.go:171","msg":"trace[2049107235] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"123.443983ms","start":"2026-05-19T18:28:09.817478Z","end":"2026-05-19T18:28:09.940922Z","steps":["trace[2049107235] 'process raft request' (duration: 119.213726ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:33.200433Z","caller":"traceutil/trace.go:171","msg":"trace[819087619] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"436.303152ms","start":"2026-05-19T18:28:32.76411Z","end":"2026-05-19T18:28:33.200414Z","steps":["trace[819087619] 'process raft request' (duration: 436.117858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:33.200526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.536859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:28:33.200558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:32.764094Z","time spent":"436.391995ms","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:33.200568Z","caller":"traceutil/trace.go:171","msg":"trace[60727049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"287.639042ms","start":"2026-05-19T18:28:32.912919Z","end":"2026-05-19T18:28:33.200558Z","steps":["trace[60727049] 'agreement among raft nodes before linearized reading' (duration: 287.541079ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:33.200395Z","caller":"traceutil/trace.go:171","msg":"trace[114051480] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"287.410846ms","start":"2026-05-19T18:28:32.912958Z","end":"2026-05-19T18:28:33.200368Z","steps":["trace[114051480] 'read index received' (duration: 287.16409ms)","trace[114051480] 'applied index is now lower than readState.Index' (duration: 245.066µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:33.200852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.850732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:33.200955Z","caller":"traceutil/trace.go:171","msg":"trace[1627764488] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1028; }","duration":"195.019216ms","start":"2026-05-19T18:28:33.005916Z","end":"2026-05-19T18:28:33.200935Z","steps":["trace[1627764488] 'agreement among raft nodes before linearized reading' (duration: 194.867632ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:34.241101Z","caller":"traceutil/trace.go:171","msg":"trace[1660726569] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"300.870615ms","start":"2026-05-19T18:28:33.940204Z","end":"2026-05-19T18:28:34.241075Z","steps":["trace[1660726569] 'read index received' (duration: 300.537447ms)","trace[1660726569] 'applied index is now lower than readState.Index' (duration: 332.028µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:34.241279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.045369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:28:34.241338Z","caller":"traceutil/trace.go:171","msg":"trace[448075994] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1030; }","duration":"301.163021ms","start":"2026-05-19T18:28:33.940155Z","end":"2026-05-19T18:28:34.241318Z","steps":["trace[448075994] 'agreement among raft nodes before linearized reading' (duration: 301.059629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:34.241387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.218531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-19T18:28:34.241397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:33.940132Z","time spent":"301.249314ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:28:34.241432Z","caller":"traceutil/trace.go:171","msg":"trace[1457300729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"297.302554ms","start":"2026-05-19T18:28:33.944118Z","end":"2026-05-19T18:28:34.24142Z","steps":["trace[1457300729] 'agreement among raft nodes before linearized reading' (duration: 297.172561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:34.24153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.280675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:34.241559Z","caller":"traceutil/trace.go:171","msg":"trace[2005834313] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1030; }","duration":"257.343196ms","start":"2026-05-19T18:28:33.984207Z","end":"2026-05-19T18:28:34.24155Z","steps":["trace[2005834313] 'agreement among raft nodes before linearized reading' (duration: 257.300535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:34.241691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.403268ms","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-05-19T18:28:34.24168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.185703ms","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:28:34.241775Z","caller":"traceutil/trace.go:171","msg":"trace[179915824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"240.570511ms","start":"2026-05-19T18:28:34.001183Z","end":"2026-05-19T18:28:34.241754Z","steps":["trace[179915824] 'agreement among raft nodes before linearized reading' (duration: 240.285485ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:34.241834Z","caller":"traceutil/trace.go:171","msg":"trace[931661481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"240.305985ms","start":"2026-05-19T18:28:34.001463Z","end":"2026-05-19T18:28:34.241769Z","steps":["trace[931661481] 'agreement among raft nodes before linearized reading' (duration: 240.015569ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:34.241288Z","caller":"traceutil/trace.go:171","msg":"trace[505107031] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"326.33592ms","start":"2026-05-19T18:28:33.914919Z","end":"2026-05-19T18:28:34.241255Z","steps":["trace[505107031] 'process raft request' (duration: 325.925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:34.242376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:33.914895Z","time spent":"327.389743ms","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:34.467632Z","caller":"traceutil/trace.go:171","msg":"trace[1113243956] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"221.853712ms","start":"2026-05-19T18:28:34.245758Z","end":"2026-05-19T18:28:34.467611Z","steps":["trace[1113243956] 'process raft request' (duration: 179.468989ms)","trace[1113243956] 'compare' (duration: 42.294491ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:34.508893Z","caller":"traceutil/trace.go:171","msg":"trace[518819688] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"262.779711ms","start":"2026-05-19T18:28:34.246044Z","end":"2026-05-19T18:28:34.508824Z","steps":["trace[518819688] 'process raft request' (duration: 262.600946ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:34.509091Z","caller":"traceutil/trace.go:171","msg":"trace[1227956618] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"262.186658ms","start":"2026-05-19T18:28:34.24688Z","end":"2026-05-19T18:28:34.509067Z","steps":["trace[1227956618] 'process raft request' (duration: 261.877131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:35.852157Z","caller":"traceutil/trace.go:171","msg":"trace[1905637833] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"170.242497ms","start":"2026-05-19T18:28:35.681879Z","end":"2026-05-19T18:28:35.852121Z","steps":["trace[1905637833] 'process raft request' (duration: 169.727305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:49.312168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.368785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:49.312273Z","caller":"traceutil/trace.go:171","msg":"trace[59151196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1131; }","duration":"402.531919ms","start":"2026-05-19T18:28:48.909715Z","end":"2026-05-19T18:28:49.312247Z","steps":["trace[59151196] 'range keys from in-memory index tree' (duration: 402.238352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:49.312334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:48.909695Z","time spent":"402.624361ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:29:02.499784Z","caller":"traceutil/trace.go:171","msg":"trace[132689194] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"118.776106ms","start":"2026-05-19T18:29:02.380984Z","end":"2026-05-19T18:29:02.49976Z","steps":["trace[132689194] 'process raft request' (duration: 118.50694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:02.786708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.521414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T18:29:02.786719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.06208ms","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-05-19T18:29:02.786978Z","caller":"traceutil/trace.go:171","msg":"trace[159779128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"170.667577ms","start":"2026-05-19T18:29:02.616111Z","end":"2026-05-19T18:29:02.786778Z","steps":["trace[159779128] 'range keys from in-memory index tree' (duration: 170.280588ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:02.786997Z","caller":"traceutil/trace.go:171","msg":"trace[77221987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1174; }","duration":"142.359827ms","start":"2026-05-19T18:29:02.644619Z","end":"2026-05-19T18:29:02.786979Z","steps":["trace[77221987] 'range keys from in-memory index tree' (duration: 141.886477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:02.786742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.988132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:29:02.7871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.045301ms","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-05-19T18:29:02.787135Z","caller":"traceutil/trace.go:171","msg":"trace[1020090370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"142.123692ms","start":"2026-05-19T18:29:02.645001Z","end":"2026-05-19T18:29:02.787125Z","steps":["trace[1020090370] 'range keys from in-memory index tree' (duration: 141.896226ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:02.787146Z","caller":"traceutil/trace.go:171","msg":"trace[421979804] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1174; }","duration":"211.442493ms","start":"2026-05-19T18:29:02.575677Z","end":"2026-05-19T18:29:02.787119Z","steps":["trace[421979804] 'count revisions from in-memory index tree' (duration: 210.903319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:05.915045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.302364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:29:05.915136Z","caller":"traceutil/trace.go:171","msg":"trace[1467165973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"184.437147ms","start":"2026-05-19T18:29:05.730676Z","end":"2026-05-19T18:29:05.915114Z","steps":["trace[1467165973] 'range keys from in-memory index tree' (duration: 184.216822ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:06.637601Z","caller":"traceutil/trace.go:171","msg":"trace[226950226] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"185.620554ms","start":"2026-05-19T18:29:06.451955Z","end":"2026-05-19T18:29:06.637576Z","steps":["trace[226950226] 'read index received' (duration: 164.639474ms)","trace[226950226] 'applied index is now lower than readState.Index' (duration: 20.98013ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:29:06.637663Z","caller":"traceutil/trace.go:171","msg":"trace[139683675] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"233.584032ms","start":"2026-05-19T18:29:06.404055Z","end":"2026-05-19T18:29:06.63764Z","steps":["trace[139683675] 'process raft request' (duration: 212.52542ms)","trace[139683675] 'compare' (duration: 20.857468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:29:06.637779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.804029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-19T18:29:06.637806Z","caller":"traceutil/trace.go:171","msg":"trace[454750899] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1211; }","duration":"185.87038ms","start":"2026-05-19T18:29:06.451926Z","end":"2026-05-19T18:29:06.637796Z","steps":["trace[454750899] 'agreement among raft nodes before linearized reading' (duration: 185.754756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.059731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.978366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:29:07.060002Z","caller":"traceutil/trace.go:171","msg":"trace[929577663] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"419.665827ms","start":"2026-05-19T18:29:06.640315Z","end":"2026-05-19T18:29:07.059981Z","steps":["trace[929577663] 'read index received' (duration: 115.14861ms)","trace[929577663] 'applied index is now lower than readState.Index' (duration: 304.515497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:29:07.060273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.945054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T18:29:07.060321Z","caller":"traceutil/trace.go:171","msg":"trace[48785827] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1212; }","duration":"420.023546ms","start":"2026-05-19T18:29:06.640284Z","end":"2026-05-19T18:29:07.060308Z","steps":["trace[48785827] 'agreement among raft nodes before linearized reading' (duration: 419.841042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.06037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:29:06.640268Z","time spent":"420.091947ms","remote":"127.0.0.1:34610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-19T18:29:07.060279Z","caller":"traceutil/trace.go:171","msg":"trace[785779513] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"548.264504ms","start":"2026-05-19T18:29:06.511975Z","end":"2026-05-19T18:29:07.06024Z","steps":["trace[785779513] 'process raft request' (duration: 243.527251ms)","trace[785779513] 'compare' (duration: 303.419104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:29:07.060847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:29:06.511951Z","time spent":"548.634302ms","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:29:07.095845Z","caller":"traceutil/trace.go:171","msg":"trace[1496261377] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"453.415251ms","start":"2026-05-19T18:29:06.642398Z","end":"2026-05-19T18:29:07.095813Z","steps":["trace[1496261377] 'process raft request' (duration: 453.184305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.095923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.441604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:29:07.095985Z","caller":"traceutil/trace.go:171","msg":"trace[1292000080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"183.540375ms","start":"2026-05-19T18:29:06.912429Z","end":"2026-05-19T18:29:07.095969Z","steps":["trace[1292000080] 'agreement among raft nodes before linearized reading' (duration: 183.412122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.096004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.898167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:29:07.096094Z","caller":"traceutil/trace.go:171","msg":"trace[1984168939] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1213; }","duration":"202.126172ms","start":"2026-05-19T18:29:06.893951Z","end":"2026-05-19T18:29:07.096077Z","steps":["trace[1984168939] 'agreement among raft nodes before linearized reading' (duration: 201.834085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.096154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.894595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-19T18:29:07.096197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:29:06.642387Z","time spent":"453.524943ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:29:07.096211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.827933ms","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-05-19T18:29:07.096299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.075354ms","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-05-19T18:29:07.09633Z","caller":"traceutil/trace.go:171","msg":"trace[2114124546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"282.138765ms","start":"2026-05-19T18:29:06.814183Z","end":"2026-05-19T18:29:07.096322Z","steps":["trace[2114124546] 'agreement among raft nodes before linearized reading' (duration: 282.071584ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:07.096232Z","caller":"traceutil/trace.go:171","msg":"trace[1732230786] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1213; }","duration":"455.003537ms","start":"2026-05-19T18:29:06.641213Z","end":"2026-05-19T18:29:07.096217Z","steps":["trace[1732230786] 'agreement among raft nodes before linearized reading' (duration: 454.7035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.096397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:29:06.641209Z","time spent":"455.174631ms","remote":"127.0.0.1:34846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T18:29:07.09629Z","caller":"traceutil/trace.go:171","msg":"trace[755747952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"279.936055ms","start":"2026-05-19T18:29:06.816329Z","end":"2026-05-19T18:29:07.096265Z","steps":["trace[755747952] 'agreement among raft nodes before linearized reading' (duration: 279.819262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:07.09607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.934622ms","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:29:07.09655Z","caller":"traceutil/trace.go:171","msg":"trace[1191909774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"279.497164ms","start":"2026-05-19T18:29:06.817037Z","end":"2026-05-19T18:29:07.096535Z","steps":["trace[1191909774] 'agreement among raft nodes before linearized reading' (duration: 278.797288ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:07.275413Z","caller":"traceutil/trace.go:171","msg":"trace[1795765067] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"174.345015ms","start":"2026-05-19T18:29:07.101031Z","end":"2026-05-19T18:29:07.275376Z","steps":["trace[1795765067] 'process raft request' (duration: 99.842329ms)","trace[1795765067] 'compare' (duration: 74.356203ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:29:07.286873Z","caller":"traceutil/trace.go:171","msg":"trace[2031802487] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"182.494672ms","start":"2026-05-19T18:29:07.104352Z","end":"2026-05-19T18:29:07.286846Z","steps":["trace[2031802487] 'process raft request' (duration: 182.417411ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:07.287162Z","caller":"traceutil/trace.go:171","msg":"trace[587925875] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"185.585163ms","start":"2026-05-19T18:29:07.101554Z","end":"2026-05-19T18:29:07.287139Z","steps":["trace[587925875] 'process raft request' (duration: 185.00263ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:07.287186Z","caller":"traceutil/trace.go:171","msg":"trace[385245051] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"185.073662ms","start":"2026-05-19T18:29:07.102096Z","end":"2026-05-19T18:29:07.287169Z","steps":["trace[385245051] 'process raft request' (duration: 184.614971ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:07.787717Z","caller":"traceutil/trace.go:171","msg":"trace[1851424119] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"163.585969ms","start":"2026-05-19T18:29:07.624084Z","end":"2026-05-19T18:29:07.78767Z","steps":["trace[1851424119] 'process raft request' (duration: 163.387854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:08.145391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.600345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:29:08.14546Z","caller":"traceutil/trace.go:171","msg":"trace[1420101754] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1219; }","duration":"264.684796ms","start":"2026-05-19T18:29:07.880759Z","end":"2026-05-19T18:29:08.145444Z","steps":["trace[1420101754] 'count revisions from in-memory index tree' (duration: 264.498592ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:29:19.824834Z","caller":"traceutil/trace.go:171","msg":"trace[821333267] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"237.800119ms","start":"2026-05-19T18:29:19.587005Z","end":"2026-05-19T18:29:19.824805Z","steps":["trace[821333267] 'process raft request' (duration: 237.677876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:20.133864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.623832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:29:20.133949Z","caller":"traceutil/trace.go:171","msg":"trace[1602203614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"222.759074ms","start":"2026-05-19T18:29:19.911171Z","end":"2026-05-19T18:29:20.13393Z","steps":["trace[1602203614] 'range keys from in-memory index tree' (duration: 222.496049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:20.134241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.708413ms","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:29:20.134331Z","caller":"traceutil/trace.go:171","msg":"trace[1427491673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"297.865086ms","start":"2026-05-19T18:29:19.836446Z","end":"2026-05-19T18:29:20.134311Z","steps":["trace[1427491673] 'range keys from in-memory index tree' (duration: 297.527148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:21.964741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.16036ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T18:30:22.780983Z","caller":"traceutil/trace.go:171","msg":"trace[1701370763] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"114.740058ms","start":"2026-05-19T18:30:22.666213Z","end":"2026-05-19T18:30:22.780953Z","steps":["trace[1701370763] 'process raft request' (duration: 54.823516ms)","trace[1701370763] 'compare' (duration: 59.606065ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:32:50.76978Z","caller":"traceutil/trace.go:171","msg":"trace[1820415590] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"124.681004ms","start":"2026-05-19T18:32:50.645067Z","end":"2026-05-19T18:32:50.769748Z","steps":["trace[1820415590] 'process raft request' (duration: 70.977996ms)","trace[1820415590] 'compare' (duration: 53.440662ms)"],"step_count":2}