{"level":"warn","ts":"2026-04-27T12:01:52.348505Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:01:52.348608Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249: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.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249: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-04-27T12:01:52.348687Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:01:52.3487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-04-27T12:01:52.348718Z","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-04-27T12:01:52.349358Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-04-27T12:01:52.349443Z","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.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249: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-04-27T12:01:52.357428Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.770219ms"} {"level":"info","ts":"2026-04-27T12:01:52.367056Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-04-27T12:01:52.367166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T12:01:52.36723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-04-27T12:01:52.367248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T12:01:52.367287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-04-27T12:01:52.367365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-04-27T12:01:52.37661Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T12:01:52.378212Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T12:01:52.379391Z","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-04-27T12:01:52.382323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T12:01:52.38258Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T12:01:52.382722Z","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-04-27T12:01:52.382794Z","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-04-27T12:01:52.382809Z","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-04-27T12:01:52.385316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-04-27T12:01:52.385475Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-04-27T12:01:52.387499Z","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-04-27T12:01:52.387681Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-04-27T12:01:52.38773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-04-27T12:01:52.388848Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T12:01:52.388885Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T12:01:52.96827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T12:01:52.968318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T12:01:52.968339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-04-27T12:01:52.96835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-04-27T12:01:52.968355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-04-27T12:01:52.968363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-04-27T12:01:52.96837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-04-27T12:01:52.970482Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:01:52.972872Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T12:01:52.97365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:01:52.973696Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:01:52.974443Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T12:01:52.974536Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T12:01:52.974616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:01:52.974713Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:01:52.974736Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:01:52.975583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T12:01:52.977022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"warn","ts":"2026-04-27T12:01:55.760149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363759785073766416,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T12:01:55.831403Z","caller":"traceutil/trace.go:171","msg":"trace[1491116966] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"571.641859ms","start":"2026-04-27T12:01:55.259738Z","end":"2026-04-27T12:01:55.83138Z","steps":["trace[1491116966] 'read index received' (duration: 571.424625ms)","trace[1491116966] 'applied index is now lower than readState.Index' (duration: 215.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:55.831476Z","caller":"traceutil/trace.go:171","msg":"trace[457635261] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"573.569704ms","start":"2026-04-27T12:01:55.257871Z","end":"2026-04-27T12:01:55.83144Z","steps":["trace[457635261] 'process raft request' (duration: 573.37351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:55.83155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.790351ms","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-04-27T12:01:55.831631Z","caller":"traceutil/trace.go:171","msg":"trace[1328924228] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:109; }","duration":"571.886993ms","start":"2026-04-27T12:01:55.259732Z","end":"2026-04-27T12:01:55.831619Z","steps":["trace[1328924228] 'agreement among raft nodes before linearized reading' (duration: 571.73779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:55.831656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:55.259674Z","time spent":"571.975564ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-04-27T12:01:55.831933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.237962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T12:01:55.832087Z","caller":"traceutil/trace.go:171","msg":"trace[547225436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"472.520628ms","start":"2026-04-27T12:01:55.359523Z","end":"2026-04-27T12:01:55.832044Z","steps":["trace[547225436] 'agreement among raft nodes before linearized reading' (duration: 472.201022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:55.832133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:55.359503Z","time spent":"472.61879ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:01:55.832208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:55.257853Z","time spent":"573.736577ms","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:01:56.102316Z","caller":"traceutil/trace.go:171","msg":"trace[1926696594] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"255.583928ms","start":"2026-04-27T12:01:55.846706Z","end":"2026-04-27T12:01:56.10229Z","steps":["trace[1926696594] 'process raft request' (duration: 198.528336ms)","trace[1926696594] 'compare' (duration: 56.926759ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:56.50585Z","caller":"traceutil/trace.go:171","msg":"trace[1190675544] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"114.749554ms","start":"2026-04-27T12:01:56.391065Z","end":"2026-04-27T12:01:56.505814Z","steps":["trace[1190675544] 'process raft request' (duration: 107.887725ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.779581Z","caller":"traceutil/trace.go:171","msg":"trace[1454697347] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"119.624305ms","start":"2026-04-27T12:02:23.65992Z","end":"2026-04-27T12:02:23.779544Z","steps":["trace[1454697347] 'process raft request' (duration: 119.433422ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.788566Z","caller":"traceutil/trace.go:171","msg":"trace[1220666012] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"128.283917ms","start":"2026-04-27T12:02:23.660259Z","end":"2026-04-27T12:02:23.788543Z","steps":["trace[1220666012] 'process raft request' (duration: 127.998411ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.788841Z","caller":"traceutil/trace.go:171","msg":"trace[1965531100] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"116.354754ms","start":"2026-04-27T12:02:23.672476Z","end":"2026-04-27T12:02:23.788831Z","steps":["trace[1965531100] 'process raft request' (duration: 116.232011ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.788933Z","caller":"traceutil/trace.go:171","msg":"trace[1826847467] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"128.557382ms","start":"2026-04-27T12:02:23.660369Z","end":"2026-04-27T12:02:23.788927Z","steps":["trace[1826847467] 'process raft request' (duration: 128.116974ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.788989Z","caller":"traceutil/trace.go:171","msg":"trace[472956078] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"118.976263ms","start":"2026-04-27T12:02:23.669964Z","end":"2026-04-27T12:02:23.78894Z","steps":["trace[472956078] 'process raft request' (duration: 118.691058ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.789022Z","caller":"traceutil/trace.go:171","msg":"trace[2055977987] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"122.783145ms","start":"2026-04-27T12:02:23.666234Z","end":"2026-04-27T12:02:23.789017Z","steps":["trace[2055977987] 'process raft request' (duration: 122.306506ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.789013Z","caller":"traceutil/trace.go:171","msg":"trace[1364483513] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"115.545089ms","start":"2026-04-27T12:02:23.67345Z","end":"2026-04-27T12:02:23.788995Z","steps":["trace[1364483513] 'process raft request' (duration: 115.294674ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.78885Z","caller":"traceutil/trace.go:171","msg":"trace[1581531081] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"121.630573ms","start":"2026-04-27T12:02:23.667199Z","end":"2026-04-27T12:02:23.78883Z","steps":["trace[1581531081] 'process raft request' (duration: 121.385408ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.789136Z","caller":"traceutil/trace.go:171","msg":"trace[67621224] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"100.897223ms","start":"2026-04-27T12:02:23.688233Z","end":"2026-04-27T12:02:23.78913Z","steps":["trace[67621224] 'process raft request' (duration: 100.613668ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:23.789285Z","caller":"traceutil/trace.go:171","msg":"trace[1878041462] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"109.724219ms","start":"2026-04-27T12:02:23.679556Z","end":"2026-04-27T12:02:23.78928Z","steps":["trace[1878041462] 'process raft request' (duration: 109.23531ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:30.063665Z","caller":"traceutil/trace.go:171","msg":"trace[1881110722] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"199.344872ms","start":"2026-04-27T12:02:29.864295Z","end":"2026-04-27T12:02:30.063639Z","steps":["trace[1881110722] 'process raft request' (duration: 117.923203ms)","trace[1881110722] 'compare' (duration: 80.987981ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:30.656413Z","caller":"traceutil/trace.go:171","msg":"trace[1284411655] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"430.281827ms","start":"2026-04-27T12:02:30.226107Z","end":"2026-04-27T12:02:30.656389Z","steps":["trace[1284411655] 'process raft request' (duration: 429.202347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:30.656867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.226053Z","time spent":"430.525931ms","remote":"127.0.0.1:41576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:02:31.471873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.013209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:02:31.472156Z","caller":"traceutil/trace.go:171","msg":"trace[1019024745] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"633.718695ms","start":"2026-04-27T12:02:30.838412Z","end":"2026-04-27T12:02:31.47213Z","steps":["trace[1019024745] 'process raft request' (duration: 94.588866ms)","trace[1019024745] 'compare' (duration: 537.438898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:31.472356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.838392Z","time spent":"633.918339ms","remote":"127.0.0.1:41562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:02:31.483633Z","caller":"traceutil/trace.go:171","msg":"trace[14914429] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"645.033878ms","start":"2026-04-27T12:02:30.838553Z","end":"2026-04-27T12:02:31.483587Z","steps":["trace[14914429] 'process raft request' (duration: 644.703081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:31.483822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.838548Z","time spent":"645.184951ms","remote":"127.0.0.1:41562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:02:31.483942Z","caller":"traceutil/trace.go:171","msg":"trace[1682815294] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"643.633601ms","start":"2026-04-27T12:02:30.840255Z","end":"2026-04-27T12:02:31.483889Z","steps":["trace[1682815294] 'process raft request' (duration: 643.211413ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:31.483939Z","caller":"traceutil/trace.go:171","msg":"trace[1995178922] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:642; }","duration":"586.898955ms","start":"2026-04-27T12:02:30.896882Z","end":"2026-04-27T12:02:31.483781Z","steps":["trace[1995178922] 'read index received' (duration: 36.137367ms)","trace[1995178922] 'applied index is now lower than readState.Index' (duration: 550.760268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:31.484143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.840233Z","time spent":"643.812715ms","remote":"127.0.0.1:41648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:31.48403Z","caller":"traceutil/trace.go:171","msg":"trace[573968752] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"643.554811ms","start":"2026-04-27T12:02:30.840415Z","end":"2026-04-27T12:02:31.48397Z","steps":["trace[573968752] 'process raft request' (duration: 643.174944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:31.484271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.316675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:02:31.484256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.355424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T12:02:31.484292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.840401Z","time spent":"643.805545ms","remote":"127.0.0.1:41692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:31.484326Z","caller":"traceutil/trace.go:171","msg":"trace[432804090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"440.415897ms","start":"2026-04-27T12:02:31.043891Z","end":"2026-04-27T12:02:31.484307Z","steps":["trace[432804090] 'agreement among raft nodes before linearized reading' (duration: 440.313775ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:31.484337Z","caller":"traceutil/trace.go:171","msg":"trace[418480188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"587.493447ms","start":"2026-04-27T12:02:30.896823Z","end":"2026-04-27T12:02:31.484316Z","steps":["trace[418480188] 'agreement among raft nodes before linearized reading' (duration: 587.281893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:31.48436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:31.043869Z","time spent":"440.483948ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:02:31.484375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:30.896789Z","time spent":"587.577299ms","remote":"127.0.0.1:41724","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-04-27T12:03:21.989146Z","caller":"traceutil/trace.go:171","msg":"trace[912813664] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"220.806518ms","start":"2026-04-27T12:03:21.7683Z","end":"2026-04-27T12:03:21.989107Z","steps":["trace[912813664] 'process raft request' (duration: 177.969935ms)","trace[912813664] 'compare' (duration: 42.687159ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:03:22.24146Z","caller":"traceutil/trace.go:171","msg":"trace[219156982] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"228.71708ms","start":"2026-04-27T12:03:22.012703Z","end":"2026-04-27T12:03:22.24142Z","steps":["trace[219156982] 'process raft request' (duration: 228.21428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:22.241794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.072446ms","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-04-27T12:03:22.241915Z","caller":"traceutil/trace.go:171","msg":"trace[1859449293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"107.268359ms","start":"2026-04-27T12:03:22.134618Z","end":"2026-04-27T12:03:22.241887Z","steps":["trace[1859449293] 'agreement among raft nodes before linearized reading' (duration: 106.885672ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:03:22.241752Z","caller":"traceutil/trace.go:171","msg":"trace[2065706426] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"106.704159ms","start":"2026-04-27T12:03:22.134672Z","end":"2026-04-27T12:03:22.241376Z","steps":["trace[2065706426] 'read index received' (duration: 52.671871ms)","trace[2065706426] 'applied index is now lower than readState.Index' (duration: 54.030998ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:03:42.580159Z","caller":"traceutil/trace.go:171","msg":"trace[802522706] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"261.421548ms","start":"2026-04-27T12:03:42.318719Z","end":"2026-04-27T12:03:42.580141Z","steps":["trace[802522706] 'process raft request' (duration: 261.104772ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:03:42.580142Z","caller":"traceutil/trace.go:171","msg":"trace[496693512] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"157.611625ms","start":"2026-04-27T12:03:42.422498Z","end":"2026-04-27T12:03:42.58011Z","steps":["trace[496693512] 'read index received' (duration: 157.253349ms)","trace[496693512] 'applied index is now lower than readState.Index' (duration: 357.036µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:03:42.580361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.8367ms","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-04-27T12:03:42.580402Z","caller":"traceutil/trace.go:171","msg":"trace[669255153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"157.947772ms","start":"2026-04-27T12:03:42.422443Z","end":"2026-04-27T12:03:42.580391Z","steps":["trace[669255153] 'agreement among raft nodes before linearized reading' (duration: 157.753918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:42.580579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.618754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:03:42.580673Z","caller":"traceutil/trace.go:171","msg":"trace[247464496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"157.763867ms","start":"2026-04-27T12:03:42.422872Z","end":"2026-04-27T12:03:42.580636Z","steps":["trace[247464496] 'agreement among raft nodes before linearized reading' (duration: 157.505352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:46.159938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.796433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:03:46.160088Z","caller":"traceutil/trace.go:171","msg":"trace[516923887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"116.082018ms","start":"2026-04-27T12:03:46.043982Z","end":"2026-04-27T12:03:46.160064Z","steps":["trace[516923887] 'range keys from in-memory index tree' (duration: 115.65833ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:03:58.399855Z","caller":"traceutil/trace.go:171","msg":"trace[31281721] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"355.055265ms","start":"2026-04-27T12:03:58.044768Z","end":"2026-04-27T12:03:58.399823Z","steps":["trace[31281721] 'read index received' (duration: 354.842031ms)","trace[31281721] 'applied index is now lower than readState.Index' (duration: 212.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:03:58.399912Z","caller":"traceutil/trace.go:171","msg":"trace[1506815619] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"403.695658ms","start":"2026-04-27T12:03:57.996161Z","end":"2026-04-27T12:03:58.399856Z","steps":["trace[1506815619] 'process raft request' (duration: 403.479124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:58.400128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.32999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:03:58.400176Z","caller":"traceutil/trace.go:171","msg":"trace[1025537979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"355.438852ms","start":"2026-04-27T12:03:58.044724Z","end":"2026-04-27T12:03:58.400163Z","steps":["trace[1025537979] 'agreement among raft nodes before linearized reading' (duration: 355.301179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:58.400172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:03:57.996142Z","time spent":"403.899923ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:03:58.400216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:03:58.044702Z","time spent":"355.506823ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:04:10.81523Z","caller":"traceutil/trace.go:171","msg":"trace[1191733974] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"129.329521ms","start":"2026-04-27T12:04:10.685877Z","end":"2026-04-27T12:04:10.815206Z","steps":["trace[1191733974] 'process raft request' (duration: 128.380712ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:14.631739Z","caller":"traceutil/trace.go:171","msg":"trace[1301758619] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"155.710406ms","start":"2026-04-27T12:04:14.476007Z","end":"2026-04-27T12:04:14.631718Z","steps":["trace[1301758619] 'process raft request' (duration: 155.597864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:15.013771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.281918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:04:15.0139Z","caller":"traceutil/trace.go:171","msg":"trace[329253215] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"371.555917ms","start":"2026-04-27T12:04:14.642325Z","end":"2026-04-27T12:04:15.013881Z","steps":["trace[329253215] 'process raft request' (duration: 214.086147ms)","trace[329253215] 'compare' (duration: 156.95311ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:15.01398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:04:14.642309Z","time spent":"371.624958ms","remote":"127.0.0.1:41724","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-04-27T12:04:15.02229Z","caller":"traceutil/trace.go:171","msg":"trace[1049422850] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"185.074719ms","start":"2026-04-27T12:04:14.837189Z","end":"2026-04-27T12:04:15.022264Z","steps":["trace[1049422850] 'read index received' (duration: 19.266008ms)","trace[1049422850] 'applied index is now lower than readState.Index' (duration: 165.807631ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:04:15.022374Z","caller":"traceutil/trace.go:171","msg":"trace[740119968] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"195.721054ms","start":"2026-04-27T12:04:14.826631Z","end":"2026-04-27T12:04:15.022352Z","steps":["trace[740119968] 'process raft request' (duration: 195.49725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:15.022488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.284453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:04:15.022551Z","caller":"traceutil/trace.go:171","msg":"trace[694569545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"185.379445ms","start":"2026-04-27T12:04:14.837155Z","end":"2026-04-27T12:04:15.022535Z","steps":["trace[694569545] 'agreement among raft nodes before linearized reading' (duration: 185.201752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:15.022555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.782225ms","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-04-27T12:04:15.022594Z","caller":"traceutil/trace.go:171","msg":"trace[1246440915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"183.866826ms","start":"2026-04-27T12:04:14.838716Z","end":"2026-04-27T12:04:15.022583Z","steps":["trace[1246440915] 'agreement among raft nodes before linearized reading' (duration: 183.708773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:16.960119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.734524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:04:16.960234Z","caller":"traceutil/trace.go:171","msg":"trace[2025905027] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"228.085654ms","start":"2026-04-27T12:04:16.732132Z","end":"2026-04-27T12:04:16.960218Z","steps":["trace[2025905027] 'read index received' (duration: 32.172117ms)","trace[2025905027] 'applied index is now lower than readState.Index' (duration: 195.912107ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:04:16.960262Z","caller":"traceutil/trace.go:171","msg":"trace[2014945501] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"317.921868ms","start":"2026-04-27T12:04:16.642303Z","end":"2026-04-27T12:04:16.960225Z","steps":["trace[2014945501] 'process raft request' (duration: 122.00083ms)","trace[2014945501] 'compare' (duration: 195.565471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:16.960301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.184657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:04:16.960341Z","caller":"traceutil/trace.go:171","msg":"trace[1825506500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1250; }","duration":"228.370161ms","start":"2026-04-27T12:04:16.731961Z","end":"2026-04-27T12:04:16.960332Z","steps":["trace[1825506500] 'agreement among raft nodes before linearized reading' (duration: 228.302169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:16.960436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:04:16.64228Z","time spent":"318.060971ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:04:17.184365Z","caller":"traceutil/trace.go:171","msg":"trace[403180532] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1301; }","duration":"142.101806ms","start":"2026-04-27T12:04:17.042236Z","end":"2026-04-27T12:04:17.184337Z","steps":["trace[403180532] 'read index received' (duration: 8.3301ms)","trace[403180532] 'applied index is now lower than readState.Index' (duration: 133.770516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:17.18479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.94518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-04-27T12:04:17.1848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.540744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:04:17.184851Z","caller":"traceutil/trace.go:171","msg":"trace[1564196015] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1254; }","duration":"132.055472ms","start":"2026-04-27T12:04:17.05278Z","end":"2026-04-27T12:04:17.184836Z","steps":["trace[1564196015] 'agreement among raft nodes before linearized reading' (duration: 131.93686ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:17.184873Z","caller":"traceutil/trace.go:171","msg":"trace[1075972335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"142.672507ms","start":"2026-04-27T12:04:17.042182Z","end":"2026-04-27T12:04:17.184855Z","steps":["trace[1075972335] 'agreement among raft nodes before linearized reading' (duration: 142.519484ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:17.185167Z","caller":"traceutil/trace.go:171","msg":"trace[2078667269] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"162.911054ms","start":"2026-04-27T12:04:17.022239Z","end":"2026-04-27T12:04:17.18515Z","steps":["trace[2078667269] 'process raft request' (duration: 94.207846ms)","trace[2078667269] 'compare' (duration: 67.281321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:17.185273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.064275ms","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-04-27T12:04:17.185331Z","caller":"traceutil/trace.go:171","msg":"trace[217488671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"105.154867ms","start":"2026-04-27T12:04:17.080162Z","end":"2026-04-27T12:04:17.185317Z","steps":["trace[217488671] 'agreement among raft nodes before linearized reading' (duration: 104.995374ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:17.185403Z","caller":"traceutil/trace.go:171","msg":"trace[1195822410] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"157.029202ms","start":"2026-04-27T12:04:17.028356Z","end":"2026-04-27T12:04:17.185385Z","steps":["trace[1195822410] 'process raft request' (duration: 155.90765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:17.185523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.131045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:04:17.185577Z","caller":"traceutil/trace.go:171","msg":"trace[627752074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"132.218237ms","start":"2026-04-27T12:04:17.053345Z","end":"2026-04-27T12:04:17.185564Z","steps":["trace[627752074] 'agreement among raft nodes before linearized reading' (duration: 132.088585ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:17.378855Z","caller":"traceutil/trace.go:171","msg":"trace[1671356722] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"101.428056ms","start":"2026-04-27T12:04:17.277402Z","end":"2026-04-27T12:04:17.37883Z","steps":["trace[1671356722] 'process raft request' (duration: 26.706102ms)","trace[1671356722] 'compare' (duration: 74.470699ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:04:29.012514Z","caller":"traceutil/trace.go:171","msg":"trace[1699733921] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"182.539062ms","start":"2026-04-27T12:04:28.829947Z","end":"2026-04-27T12:04:29.012486Z","steps":["trace[1699733921] 'process raft request' (duration: 181.94818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:29.354287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.972895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:04:29.354563Z","caller":"traceutil/trace.go:171","msg":"trace[1160567391] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"338.148276ms","start":"2026-04-27T12:04:29.016378Z","end":"2026-04-27T12:04:29.354527Z","steps":["trace[1160567391] 'process raft request' (duration: 111.835604ms)","trace[1160567391] 'compare' (duration: 223.852394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:29.354692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:04:29.016357Z","time spent":"338.275818ms","remote":"127.0.0.1:41724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:04:29.354777Z","caller":"traceutil/trace.go:171","msg":"trace[1277141840] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"126.350313ms","start":"2026-04-27T12:04:29.228383Z","end":"2026-04-27T12:04:29.354733Z","steps":["trace[1277141840] 'process raft request' (duration: 126.131549ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:29.35483Z","caller":"traceutil/trace.go:171","msg":"trace[1196014020] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"223.848574ms","start":"2026-04-27T12:04:29.130917Z","end":"2026-04-27T12:04:29.354765Z","steps":["trace[1196014020] 'read index received' (duration: 28.431µs)","trace[1196014020] 'applied index is now lower than readState.Index' (duration: 223.817343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:04:29.355083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.16473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:04:29.355135Z","caller":"traceutil/trace.go:171","msg":"trace[1360208169] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1336; }","duration":"224.244382ms","start":"2026-04-27T12:04:29.130875Z","end":"2026-04-27T12:04:29.35512Z","steps":["trace[1360208169] 'agreement among raft nodes before linearized reading' (duration: 224.167659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:29.355287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.678804ms","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-04-27T12:04:29.356274Z","caller":"traceutil/trace.go:171","msg":"trace[1632228466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"122.697383ms","start":"2026-04-27T12:04:29.233545Z","end":"2026-04-27T12:04:29.356242Z","steps":["trace[1632228466] 'agreement among raft nodes before linearized reading' (duration: 121.528831ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:20.045164Z","caller":"traceutil/trace.go:171","msg":"trace[2103550313] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"139.002474ms","start":"2026-04-27T12:06:19.906128Z","end":"2026-04-27T12:06:20.04513Z","steps":["trace[2103550313] 'process raft request' (duration: 125.109037ms)","trace[2103550313] 'compare' (duration: 13.676572ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:20.045389Z","caller":"traceutil/trace.go:171","msg":"trace[1362638068] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"138.940223ms","start":"2026-04-27T12:06:19.906417Z","end":"2026-04-27T12:06:20.045357Z","steps":["trace[1362638068] 'process raft request' (duration: 138.670357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.769276Z","caller":"traceutil/trace.go:171","msg":"trace[794510348] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"134.516337ms","start":"2026-04-27T12:07:44.634733Z","end":"2026-04-27T12:07:44.769249Z","steps":["trace[794510348] 'process raft request' (duration: 134.330473ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.769558Z","caller":"traceutil/trace.go:171","msg":"trace[1127266434] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"134.800252ms","start":"2026-04-27T12:07:44.634733Z","end":"2026-04-27T12:07:44.769533Z","steps":["trace[1127266434] 'process raft request' (duration: 134.476066ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.76958Z","caller":"traceutil/trace.go:171","msg":"trace[1724842849] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"133.045448ms","start":"2026-04-27T12:07:44.636517Z","end":"2026-04-27T12:07:44.769562Z","steps":["trace[1724842849] 'process raft request' (duration: 132.932436ms)"],"step_count":1}