{"level":"warn","ts":"2026-03-19T13:29:34.389128Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T13:29:34.389239Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-03-19T13:29:34.389337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T13:29:34.389358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-19T13:29:34.389383Z","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-03-19T13:29:34.390274Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-03-19T13:29:34.390373Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-03-19T13:29:34.513607Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"122.585195ms"} {"level":"info","ts":"2026-03-19T13:29:34.549486Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-03-19T13:29:34.549596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T13:29:34.549626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-03-19T13:29:34.549662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T13:29:34.549687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-03-19T13:29:34.549822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-03-19T13:29:34.575662Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T13:29:34.577505Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T13:29:34.582114Z","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-03-19T13:29:34.586909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T13:29:34.589924Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T13:29:34.591103Z","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-03-19T13:29:34.591268Z","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-03-19T13:29:34.591279Z","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-03-19T13:29:34.592984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-03-19T13:29:34.593192Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-19T13:29:34.594056Z","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-03-19T13:29:34.594284Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-19T13:29:34.59436Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-19T13:29:34.594511Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T13:29:34.59455Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T13:29:34.750599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T13:29:34.750728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T13:29:34.750762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-03-19T13:29:34.751051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-03-19T13:29:34.751075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-19T13:29:34.751178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-03-19T13:29:34.751215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-19T13:29:34.752698Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T13:29:34.75272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:29:34.753127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:29:34.753522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T13:29:34.753545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T13:29:34.755857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T13:29:34.756632Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:29:34.757773Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:29:34.757896Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:29:34.757935Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:29:34.759754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-03-19T13:29:37.947091Z","caller":"traceutil/trace.go:171","msg":"trace[347059452] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"165.737143ms","start":"2026-03-19T13:29:37.781314Z","end":"2026-03-19T13:29:37.947051Z","steps":["trace[347059452] 'process raft request' (duration: 74.936781ms)","trace[347059452] 'compare' (duration: 90.523721ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:29:38.220738Z","caller":"traceutil/trace.go:171","msg":"trace[316982113] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"104.075218ms","start":"2026-03-19T13:29:38.116627Z","end":"2026-03-19T13:29:38.220702Z","steps":["trace[316982113] 'process raft request' (duration: 57.659215ms)","trace[316982113] 'compare' (duration: 46.231993ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:29:38.61421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.613188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:29:38.614411Z","caller":"traceutil/trace.go:171","msg":"trace[2034796351] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"162.979675ms","start":"2026-03-19T13:29:38.451407Z","end":"2026-03-19T13:29:38.614386Z","steps":["trace[2034796351] 'process raft request' (duration: 53.349956ms)","trace[2034796351] 'compare' (duration: 108.368747ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:29:39.07933Z","caller":"traceutil/trace.go:171","msg":"trace[1891353324] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"116.993623ms","start":"2026-03-19T13:29:38.962296Z","end":"2026-03-19T13:29:39.07929Z","steps":["trace[1891353324] 'process raft request' (duration: 51.543483ms)","trace[1891353324] 'compare' (duration: 65.12038ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:29:39.310893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.379981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:29:39.311023Z","caller":"traceutil/trace.go:171","msg":"trace[1139733860] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"154.55078ms","start":"2026-03-19T13:29:39.156448Z","end":"2026-03-19T13:29:39.310999Z","steps":["trace[1139733860] 'process raft request' (duration: 38.981069ms)","trace[1139733860] 'compare' (duration: 115.191561ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:29:44.121892Z","caller":"traceutil/trace.go:171","msg":"trace[852058006] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"445.584479ms","start":"2026-03-19T13:29:43.676274Z","end":"2026-03-19T13:29:44.121859Z","steps":["trace[852058006] 'process raft request' (duration: 445.406469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:29:44.122099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:29:43.676252Z","time spent":"445.744789ms","remote":"127.0.0.1:53446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:29:45.794104Z","caller":"traceutil/trace.go:171","msg":"trace[604847889] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"159.636518ms","start":"2026-03-19T13:29:45.63442Z","end":"2026-03-19T13:29:45.794056Z","steps":["trace[604847889] 'process raft request' (duration: 159.450018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:00.176433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146196912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:00.322578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.17076083s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:30:00.323016Z","caller":"traceutil/trace.go:171","msg":"trace[659655960] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"647.227072ms","start":"2026-03-19T13:29:59.675767Z","end":"2026-03-19T13:30:00.322994Z","steps":["trace[659655960] 'read index received' (duration: 647.124222ms)","trace[659655960] 'applied index is now lower than readState.Index' (duration: 101.36µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:00.323085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:29:59.15168Z","time spent":"1.17139524s","remote":"127.0.0.1:53312","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T13:30:00.323224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.430403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:00.323324Z","caller":"traceutil/trace.go:171","msg":"trace[1864163315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"647.589523ms","start":"2026-03-19T13:29:59.675711Z","end":"2026-03-19T13:30:00.3233Z","steps":["trace[1864163315] 'agreement among raft nodes before linearized reading' (duration: 647.419423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:00.323375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:29:59.675694Z","time spent":"647.671924ms","remote":"127.0.0.1:53278","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-03-19T13:30:00.830101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146196917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:01.331031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146196917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:01.831678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146196917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:01.971639Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.644941749s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:30:02.014714Z","caller":"traceutil/trace.go:171","msg":"trace[1968014451] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.688258094s","start":"2026-03-19T13:30:00.326414Z","end":"2026-03-19T13:30:02.014672Z","steps":["trace[1968014451] 'process raft request' (duration: 1.645455929s)","trace[1968014451] 'compare' (duration: 42.033143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:02.015282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.326389Z","time spent":"1.688413065s","remote":"127.0.0.1:53312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:02.062213Z","caller":"traceutil/trace.go:171","msg":"trace[746700641] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:434; }","duration":"1.732532102s","start":"2026-03-19T13:30:00.329657Z","end":"2026-03-19T13:30:02.062189Z","steps":["trace[746700641] 'read index received' (duration: 1.642227863s)","trace[746700641] 'applied index is now lower than readState.Index' (duration: 90.303349ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:02.062263Z","caller":"traceutil/trace.go:171","msg":"trace[889936064] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.733776685s","start":"2026-03-19T13:30:00.32845Z","end":"2026-03-19T13:30:02.062227Z","steps":["trace[889936064] 'process raft request' (duration: 1.733511984s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:02.062415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.328436Z","time spent":"1.733903606s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:02.062416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732742083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T13:30:02.062386Z","caller":"traceutil/trace.go:171","msg":"trace[1827264821] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"304.479944ms","start":"2026-03-19T13:30:01.757849Z","end":"2026-03-19T13:30:02.062329Z","steps":["trace[1827264821] 'process raft request' (duration: 304.295833ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:02.062457Z","caller":"traceutil/trace.go:171","msg":"trace[356846264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"1.732829933s","start":"2026-03-19T13:30:00.329616Z","end":"2026-03-19T13:30:02.062446Z","steps":["trace[356846264] 'agreement among raft nodes before linearized reading' (duration: 1.732711843s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:02.062494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.329607Z","time spent":"1.732877323s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:30:02.062531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688512225s","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-03-19T13:30:02.062552Z","caller":"traceutil/trace.go:171","msg":"trace[1839669770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"1.688551875s","start":"2026-03-19T13:30:00.373993Z","end":"2026-03-19T13:30:02.062545Z","steps":["trace[1839669770] 'agreement among raft nodes before linearized reading' (duration: 1.688510945s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:02.062572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.373982Z","time spent":"1.688584565s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:30:02.062585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:01.75782Z","time spent":"304.668114ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:02.062645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386569617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:02.062663Z","caller":"traceutil/trace.go:171","msg":"trace[807191887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.386639927s","start":"2026-03-19T13:30:00.676016Z","end":"2026-03-19T13:30:02.062656Z","steps":["trace[807191887] 'agreement among raft nodes before linearized reading' (duration: 1.386615587s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:02.062689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.675985Z","time spent":"1.386699107s","remote":"127.0.0.1:53278","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-03-19T13:30:02.062849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322133938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T13:30:02.062953Z","caller":"traceutil/trace.go:171","msg":"trace[966179854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"1.322298949s","start":"2026-03-19T13:30:00.740623Z","end":"2026-03-19T13:30:02.062922Z","steps":["trace[966179854] 'agreement among raft nodes before linearized reading' (duration: 1.322016218s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:02.062999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:00.740599Z","time spent":"1.322391999s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T13:30:04.408981Z","caller":"traceutil/trace.go:171","msg":"trace[522861893] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"300.859925ms","start":"2026-03-19T13:30:04.108094Z","end":"2026-03-19T13:30:04.408954Z","steps":["trace[522861893] 'read index received' (duration: 300.730774ms)","trace[522861893] 'applied index is now lower than readState.Index' (duration: 127.361µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:04.409091Z","caller":"traceutil/trace.go:171","msg":"trace[221627464] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"337.354057ms","start":"2026-03-19T13:30:04.071674Z","end":"2026-03-19T13:30:04.409028Z","steps":["trace[221627464] 'process raft request' (duration: 337.107026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:04.409153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.050846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T13:30:04.409272Z","caller":"traceutil/trace.go:171","msg":"trace[1052194658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"301.190886ms","start":"2026-03-19T13:30:04.108072Z","end":"2026-03-19T13:30:04.409263Z","steps":["trace[1052194658] 'agreement among raft nodes before linearized reading' (duration: 301.023966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:04.409305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.107976Z","time spent":"301.322146ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:30:04.409417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.071651Z","time spent":"337.597828ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:04.40951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.065338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-03-19T13:30:04.40954Z","caller":"traceutil/trace.go:171","msg":"trace[169958439] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:425; }","duration":"262.107518ms","start":"2026-03-19T13:30:04.147423Z","end":"2026-03-19T13:30:04.409531Z","steps":["trace[169958439] 'agreement among raft nodes before linearized reading' (duration: 261.999628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:04.409463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.532997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T13:30:04.409818Z","caller":"traceutil/trace.go:171","msg":"trace[1696241131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"291.978357ms","start":"2026-03-19T13:30:04.117817Z","end":"2026-03-19T13:30:04.409795Z","steps":["trace[1696241131] 'agreement among raft nodes before linearized reading' (duration: 291.474807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:04.410149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.629167ms","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-03-19T13:30:04.410263Z","caller":"traceutil/trace.go:171","msg":"trace[1011733271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"291.773018ms","start":"2026-03-19T13:30:04.118467Z","end":"2026-03-19T13:30:04.41024Z","steps":["trace[1011733271] 'agreement among raft nodes before linearized reading' (duration: 291.339526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:04.849426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.9471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:30:04.849545Z","caller":"traceutil/trace.go:171","msg":"trace[243135914] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"433.516707ms","start":"2026-03-19T13:30:04.416008Z","end":"2026-03-19T13:30:04.849525Z","steps":["trace[243135914] 'process raft request' (duration: 135.083406ms)","trace[243135914] 'compare' (duration: 297.77443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:04.849607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.415966Z","time spent":"433.612777ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:05.460229Z","caller":"traceutil/trace.go:171","msg":"trace[359303955] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.044165925s","start":"2026-03-19T13:30:04.416041Z","end":"2026-03-19T13:30:05.460207Z","steps":["trace[359303955] 'process raft request' (duration: 1.038538614s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:05.460366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.416007Z","time spent":"1.044289825s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:05.460407Z","caller":"traceutil/trace.go:171","msg":"trace[1268083696] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:441; }","duration":"1.028187186s","start":"2026-03-19T13:30:04.432195Z","end":"2026-03-19T13:30:05.460383Z","steps":["trace[1268083696] 'read index received' (duration: 118.874655ms)","trace[1268083696] 'applied index is now lower than readState.Index' (duration: 909.311561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:05.460601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028393616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-03-19T13:30:05.460648Z","caller":"traceutil/trace.go:171","msg":"trace[1670611443] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:428; }","duration":"1.028451866s","start":"2026-03-19T13:30:04.43218Z","end":"2026-03-19T13:30:05.460632Z","steps":["trace[1670611443] 'agreement among raft nodes before linearized reading' (duration: 1.028297786s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:05.460669Z","caller":"traceutil/trace.go:171","msg":"trace[1148006243] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.044519587s","start":"2026-03-19T13:30:04.416139Z","end":"2026-03-19T13:30:05.460658Z","steps":["trace[1148006243] 'process raft request' (duration: 1.044029125s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:05.460686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.432124Z","time spent":"1.028550146s","remote":"127.0.0.1:53358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-19T13:30:05.460723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:04.416132Z","time spent":"1.044560057s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:05.595904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.143571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-19T13:30:06.41465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.700197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:30:06.414868Z","caller":"traceutil/trace.go:171","msg":"trace[1982726335] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"119.911326ms","start":"2026-03-19T13:30:06.294935Z","end":"2026-03-19T13:30:06.414846Z","steps":["trace[1982726335] 'process raft request' (duration: 119.844776ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:06.415154Z","caller":"traceutil/trace.go:171","msg":"trace[861902766] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"120.077287ms","start":"2026-03-19T13:30:06.294895Z","end":"2026-03-19T13:30:06.414972Z","steps":["trace[861902766] 'compare' (duration: 114.499836ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:06.747726Z","caller":"traceutil/trace.go:171","msg":"trace[385573755] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"136.770271ms","start":"2026-03-19T13:30:06.610919Z","end":"2026-03-19T13:30:06.747689Z","steps":["trace[385573755] 'process raft request' (duration: 135.905949ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:06.758352Z","caller":"traceutil/trace.go:171","msg":"trace[830328321] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"145.655008ms","start":"2026-03-19T13:30:06.612677Z","end":"2026-03-19T13:30:06.758332Z","steps":["trace[830328321] 'process raft request' (duration: 145.232327ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:06.990856Z","caller":"traceutil/trace.go:171","msg":"trace[34521302] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"217.11616ms","start":"2026-03-19T13:30:06.773693Z","end":"2026-03-19T13:30:06.990809Z","steps":["trace[34521302] 'process raft request' (duration: 191.502329ms)","trace[34521302] 'compare' (duration: 25.018749ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.025586Z","caller":"traceutil/trace.go:171","msg":"trace[1069596461] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"250.636806ms","start":"2026-03-19T13:30:06.774919Z","end":"2026-03-19T13:30:07.025556Z","steps":["trace[1069596461] 'process raft request' (duration: 250.426375ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.025704Z","caller":"traceutil/trace.go:171","msg":"trace[1682823033] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"249.888385ms","start":"2026-03-19T13:30:06.775786Z","end":"2026-03-19T13:30:07.025674Z","steps":["trace[1682823033] 'process raft request' (duration: 249.729694ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.026113Z","caller":"traceutil/trace.go:171","msg":"trace[1273785003] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"249.616183ms","start":"2026-03-19T13:30:06.77648Z","end":"2026-03-19T13:30:07.026096Z","steps":["trace[1273785003] 'process raft request' (duration: 249.089872ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.026515Z","caller":"traceutil/trace.go:171","msg":"trace[445691953] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:461; }","duration":"171.71475ms","start":"2026-03-19T13:30:06.854779Z","end":"2026-03-19T13:30:07.026494Z","steps":["trace[445691953] 'read index received' (duration: 110.503789ms)","trace[445691953] 'applied index is now lower than readState.Index' (duration: 61.209921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:07.026655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.86092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-19T13:30:07.026725Z","caller":"traceutil/trace.go:171","msg":"trace[1382094907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"171.96319ms","start":"2026-03-19T13:30:06.854729Z","end":"2026-03-19T13:30:07.026692Z","steps":["trace[1382094907] 'agreement among raft nodes before linearized reading' (duration: 171.82784ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.029285Z","caller":"traceutil/trace.go:171","msg":"trace[794590585] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"198.722133ms","start":"2026-03-19T13:30:06.827953Z","end":"2026-03-19T13:30:07.026676Z","steps":["trace[794590585] 'process raft request' (duration: 198.000862ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.180588Z","caller":"traceutil/trace.go:171","msg":"trace[1610849837] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"182.521691ms","start":"2026-03-19T13:30:06.998029Z","end":"2026-03-19T13:30:07.180551Z","steps":["trace[1610849837] 'process raft request' (duration: 144.061335ms)","trace[1610849837] 'compare' (duration: 38.225815ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.180659Z","caller":"traceutil/trace.go:171","msg":"trace[1630107956] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"177.36755ms","start":"2026-03-19T13:30:07.00327Z","end":"2026-03-19T13:30:07.180638Z","steps":["trace[1630107956] 'process raft request' (duration: 177.31019ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.18087Z","caller":"traceutil/trace.go:171","msg":"trace[1470573888] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"182.495431ms","start":"2026-03-19T13:30:06.998354Z","end":"2026-03-19T13:30:07.180849Z","steps":["trace[1470573888] 'process raft request' (duration: 182.150651ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.181266Z","caller":"traceutil/trace.go:171","msg":"trace[363194294] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"148.119204ms","start":"2026-03-19T13:30:07.033115Z","end":"2026-03-19T13:30:07.181234Z","steps":["trace[363194294] 'process raft request' (duration: 147.894012ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.18133Z","caller":"traceutil/trace.go:171","msg":"trace[235936126] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"148.026044ms","start":"2026-03-19T13:30:07.033262Z","end":"2026-03-19T13:30:07.181288Z","steps":["trace[235936126] 'process raft request' (duration: 147.913464ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.181671Z","caller":"traceutil/trace.go:171","msg":"trace[1728771543] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"148.077223ms","start":"2026-03-19T13:30:07.033503Z","end":"2026-03-19T13:30:07.181581Z","steps":["trace[1728771543] 'process raft request' (duration: 147.721453ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.181826Z","caller":"traceutil/trace.go:171","msg":"trace[231086958] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:466; }","duration":"143.113543ms","start":"2026-03-19T13:30:07.038677Z","end":"2026-03-19T13:30:07.18179Z","steps":["trace[231086958] 'read index received' (duration: 103.416315ms)","trace[231086958] 'applied index is now lower than readState.Index' (duration: 39.695548ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.181986Z","caller":"traceutil/trace.go:171","msg":"trace[1749692761] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"146.629901ms","start":"2026-03-19T13:30:07.035341Z","end":"2026-03-19T13:30:07.181971Z","steps":["trace[1749692761] 'process raft request' (duration: 146.15722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:07.182161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.459924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-19T13:30:07.182232Z","caller":"traceutil/trace.go:171","msg":"trace[1011224573] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:456; }","duration":"143.590324ms","start":"2026-03-19T13:30:07.038615Z","end":"2026-03-19T13:30:07.182205Z","steps":["trace[1011224573] 'agreement among raft nodes before linearized reading' (duration: 143.397604ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.445367Z","caller":"traceutil/trace.go:171","msg":"trace[772167372] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:475; }","duration":"175.320106ms","start":"2026-03-19T13:30:07.270024Z","end":"2026-03-19T13:30:07.445344Z","steps":["trace[772167372] 'read index received' (duration: 91.42802ms)","trace[772167372] 'applied index is now lower than readState.Index' (duration: 83.888026ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.445519Z","caller":"traceutil/trace.go:171","msg":"trace[1221489447] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"250.081014ms","start":"2026-03-19T13:30:07.195381Z","end":"2026-03-19T13:30:07.445462Z","steps":["trace[1221489447] 'process raft request' (duration: 166.040868ms)","trace[1221489447] 'compare' (duration: 83.507766ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.445626Z","caller":"traceutil/trace.go:171","msg":"trace[1971377136] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"244.307572ms","start":"2026-03-19T13:30:07.201307Z","end":"2026-03-19T13:30:07.445614Z","steps":["trace[1971377136] 'process raft request' (duration: 243.935332ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.445404Z","caller":"traceutil/trace.go:171","msg":"trace[1432609343] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"244.493793ms","start":"2026-03-19T13:30:07.200887Z","end":"2026-03-19T13:30:07.445381Z","steps":["trace[1432609343] 'process raft request' (duration: 244.202273ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.445875Z","caller":"traceutil/trace.go:171","msg":"trace[1219544843] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"178.214103ms","start":"2026-03-19T13:30:07.267649Z","end":"2026-03-19T13:30:07.445863Z","steps":["trace[1219544843] 'process raft request' (duration: 177.645151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:07.446071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.023318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-03-19T13:30:07.446119Z","caller":"traceutil/trace.go:171","msg":"trace[1131353251] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:462; }","duration":"176.126818ms","start":"2026-03-19T13:30:07.269978Z","end":"2026-03-19T13:30:07.446105Z","steps":["trace[1131353251] 'agreement among raft nodes before linearized reading' (duration: 176.026398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:07.446117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.013362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-19T13:30:07.446348Z","caller":"traceutil/trace.go:171","msg":"trace[830784160] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:462; }","duration":"153.298373ms","start":"2026-03-19T13:30:07.293036Z","end":"2026-03-19T13:30:07.446334Z","steps":["trace[830784160] 'agreement among raft nodes before linearized reading' (duration: 152.812783ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.646907Z","caller":"traceutil/trace.go:171","msg":"trace[631921417] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:480; }","duration":"171.752129ms","start":"2026-03-19T13:30:07.475125Z","end":"2026-03-19T13:30:07.646877Z","steps":["trace[631921417] 'read index received' (duration: 17.957854ms)","trace[631921417] 'applied index is now lower than readState.Index' (duration: 153.792775ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.646935Z","caller":"traceutil/trace.go:171","msg":"trace[808669055] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"177.765312ms","start":"2026-03-19T13:30:07.469122Z","end":"2026-03-19T13:30:07.646888Z","steps":["trace[808669055] 'process raft request' (duration: 177.605312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:07.647089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.914309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4354"} {"level":"info","ts":"2026-03-19T13:30:07.647159Z","caller":"traceutil/trace.go:171","msg":"trace[2131821360] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:466; }","duration":"172.048339ms","start":"2026-03-19T13:30:07.475095Z","end":"2026-03-19T13:30:07.647143Z","steps":["trace[2131821360] 'agreement among raft nodes before linearized reading' (duration: 171.896089ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:07.647176Z","caller":"traceutil/trace.go:171","msg":"trace[306713960] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"178.200272ms","start":"2026-03-19T13:30:07.468954Z","end":"2026-03-19T13:30:07.647154Z","steps":["trace[306713960] 'process raft request' (duration: 144.983857ms)","trace[306713960] 'compare' (duration: 32.639294ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:07.647762Z","caller":"traceutil/trace.go:171","msg":"trace[233634267] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"164.016444ms","start":"2026-03-19T13:30:07.483702Z","end":"2026-03-19T13:30:07.647719Z","steps":["trace[233634267] 'process raft request' (duration: 163.080922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:10.084219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.889761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:30:10.084348Z","caller":"traceutil/trace.go:171","msg":"trace[918484140] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"428.640627ms","start":"2026-03-19T13:30:09.655688Z","end":"2026-03-19T13:30:10.084328Z","steps":["trace[918484140] 'process raft request' (duration: 265.559086ms)","trace[918484140] 'compare' (duration: 162.773921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:10.084446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:09.655678Z","time spent":"428.745457ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:10.084632Z","caller":"traceutil/trace.go:171","msg":"trace[68854578] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:490; }","duration":"407.421116ms","start":"2026-03-19T13:30:09.677172Z","end":"2026-03-19T13:30:10.084593Z","steps":["trace[68854578] 'read index received' (duration: 4.976201ms)","trace[68854578] 'applied index is now lower than readState.Index' (duration: 402.442385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:10.084801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.599226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:10.084851Z","caller":"traceutil/trace.go:171","msg":"trace[1588131760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"407.694536ms","start":"2026-03-19T13:30:09.677141Z","end":"2026-03-19T13:30:10.084835Z","steps":["trace[1588131760] 'agreement among raft nodes before linearized reading' (duration: 407.589716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:10.084889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:09.677129Z","time spent":"407.751837ms","remote":"127.0.0.1:53278","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-03-19T13:30:11.700323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146197035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:11.767105Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21987567s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:30:11.767684Z","caller":"traceutil/trace.go:171","msg":"trace[1811813247] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"568.142451ms","start":"2026-03-19T13:30:11.199509Z","end":"2026-03-19T13:30:11.767651Z","steps":["trace[1811813247] 'read index received' (duration: 567.743421ms)","trace[1811813247] 'applied index is now lower than readState.Index' (duration: 397.32µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:11.767989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.440863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-19T13:30:11.768095Z","caller":"traceutil/trace.go:171","msg":"trace[120994588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"568.622653ms","start":"2026-03-19T13:30:11.19945Z","end":"2026-03-19T13:30:11.768073Z","steps":["trace[120994588] 'agreement among raft nodes before linearized reading' (duration: 568.382703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:11.768161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:11.199423Z","time spent":"568.727223ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T13:30:11.768507Z","caller":"traceutil/trace.go:171","msg":"trace[1122560438] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.220654621s","start":"2026-03-19T13:30:10.547134Z","end":"2026-03-19T13:30:11.767789Z","steps":["trace[1122560438] 'process raft request' (duration: 1.220304s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:11.768659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:10.547121Z","time spent":"1.221443223s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:16.310975Z","caller":"traceutil/trace.go:171","msg":"trace[1379729142] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"115.158467ms","start":"2026-03-19T13:30:16.195795Z","end":"2026-03-19T13:30:16.310953Z","steps":["trace[1379729142] 'process raft request' (duration: 115.044467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:17.905473Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.532937065s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:30:17.906182Z","caller":"traceutil/trace.go:171","msg":"trace[876883971] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.533777358s","start":"2026-03-19T13:30:16.372372Z","end":"2026-03-19T13:30:17.906149Z","steps":["trace[876883971] 'process raft request' (duration: 1.533486736s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:17.906502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:16.372343Z","time spent":"1.533917528s","remote":"127.0.0.1:53424","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-03-19T13:30:18.538701Z","caller":"traceutil/trace.go:171","msg":"trace[341609288] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"865.011556ms","start":"2026-03-19T13:30:17.67367Z","end":"2026-03-19T13:30:18.538682Z","steps":["trace[341609288] 'read index received' (duration: 232.142567ms)","trace[341609288] 'applied index is now lower than readState.Index' (duration: 632.868289ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:18.538795Z","caller":"traceutil/trace.go:171","msg":"trace[269897104] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.685101476s","start":"2026-03-19T13:30:16.853687Z","end":"2026-03-19T13:30:18.538788Z","steps":["trace[269897104] 'process raft request' (duration: 1.684869655s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.538915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:16.85366Z","time spent":"1.685155356s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:18.539076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.401608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:18.539158Z","caller":"traceutil/trace.go:171","msg":"trace[1593209574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"865.548808ms","start":"2026-03-19T13:30:17.673588Z","end":"2026-03-19T13:30:18.539137Z","steps":["trace[1593209574] 'agreement among raft nodes before linearized reading' (duration: 865.409248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.539204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:17.673566Z","time spent":"865.629028ms","remote":"127.0.0.1:53278","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-03-19T13:30:18.53921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.097849ms","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-03-19T13:30:18.539296Z","caller":"traceutil/trace.go:171","msg":"trace[1220976125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"744.271579ms","start":"2026-03-19T13:30:17.795005Z","end":"2026-03-19T13:30:18.539276Z","steps":["trace[1220976125] 'agreement among raft nodes before linearized reading' (duration: 744.003589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.539348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:17.794974Z","time spent":"744.365149ms","remote":"127.0.0.1:53500","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-03-19T13:30:18.539477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.548304ms","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-03-19T13:30:18.539516Z","caller":"traceutil/trace.go:171","msg":"trace[63500503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"686.615564ms","start":"2026-03-19T13:30:17.852891Z","end":"2026-03-19T13:30:18.539507Z","steps":["trace[63500503] 'agreement among raft nodes before linearized reading' (duration: 686.535194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.53954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:17.852876Z","time spent":"686.658404ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:30:18.539538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.828892ms","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-03-19T13:30:18.539576Z","caller":"traceutil/trace.go:171","msg":"trace[66546094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"223.904792ms","start":"2026-03-19T13:30:18.315659Z","end":"2026-03-19T13:30:18.539564Z","steps":["trace[66546094] 'agreement among raft nodes before linearized reading' (duration: 223.837342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.539764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.518206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T13:30:18.53984Z","caller":"traceutil/trace.go:171","msg":"trace[2117756321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"687.620447ms","start":"2026-03-19T13:30:17.852199Z","end":"2026-03-19T13:30:18.539819Z","steps":["trace[2117756321] 'agreement among raft nodes before linearized reading' (duration: 687.397396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:18.539884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:17.852181Z","time spent":"687.692837ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:30:19.176607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146197114,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:19.677685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830568146197114,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:30:20.018851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.386119305s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T13:30:20.675463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999887676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-19T13:30:20.675573Z","caller":"traceutil/trace.go:171","msg":"trace[1195432614] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000083286s","start":"2026-03-19T13:30:18.675469Z","end":"2026-03-19T13:30:20.675552Z","steps":["trace[1195432614] 'agreement among raft nodes before linearized reading' (duration: 1.999947576s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:20.675624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:18.675442Z","time spent":"2.000171076s","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T13:30:21.597898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.965052658s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:30:21.598131Z","caller":"traceutil/trace.go:171","msg":"trace[889834302] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:533; }","duration":"2.922565176s","start":"2026-03-19T13:30:18.675549Z","end":"2026-03-19T13:30:21.598114Z","steps":["trace[889834302] 'read index received' (duration: 1.343435711s)","trace[889834302] 'applied index is now lower than readState.Index' (duration: 1.579128575s)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:21.598337Z","caller":"traceutil/trace.go:171","msg":"trace[1347991973] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"3.053336563s","start":"2026-03-19T13:30:18.544972Z","end":"2026-03-19T13:30:21.598308Z","steps":["trace[1347991973] 'process raft request' (duration: 3.053112363s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.598486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:18.544954Z","time spent":"3.053457193s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:21.598585Z","caller":"traceutil/trace.go:171","msg":"trace[960327112] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"3.054128864s","start":"2026-03-19T13:30:18.544448Z","end":"2026-03-19T13:30:21.598577Z","steps":["trace[960327112] 'process raft request' (duration: 88.308224ms)","trace[960327112] 'compare' (duration: 2.964913658s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:21.598623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:18.544429Z","time spent":"3.054175954s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:30:21.598617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.447146707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T13:30:21.598674Z","caller":"traceutil/trace.go:171","msg":"trace[468921992] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"3.053785393s","start":"2026-03-19T13:30:18.544883Z","end":"2026-03-19T13:30:21.598669Z","steps":["trace[468921992] 'process raft request' (duration: 3.053122762s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.598667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.460397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:30:21.59871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:18.544877Z","time spent":"3.053812283s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:21.598714Z","caller":"traceutil/trace.go:171","msg":"trace[1154258173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"921.539117ms","start":"2026-03-19T13:30:20.677162Z","end":"2026-03-19T13:30:21.598701Z","steps":["trace[1154258173] 'agreement among raft nodes before linearized reading' (duration: 921.464767ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:21.598712Z","caller":"traceutil/trace.go:171","msg":"trace[770572679] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:518; }","duration":"2.447288037s","start":"2026-03-19T13:30:19.151398Z","end":"2026-03-19T13:30:21.598686Z","steps":["trace[770572679] 'agreement among raft nodes before linearized reading' (duration: 2.447057057s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.598753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:20.677146Z","time spent":"921.600857ms","remote":"127.0.0.1:53272","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-03-19T13:30:21.598767Z","caller":"traceutil/trace.go:171","msg":"trace[1404822353] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"3.053839223s","start":"2026-03-19T13:30:18.544922Z","end":"2026-03-19T13:30:21.598761Z","steps":["trace[1404822353] 'process raft request' (duration: 3.053134443s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.598766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:19.151377Z","time spent":"2.447377057s","remote":"127.0.0.1:53312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-03-19T13:30:21.598802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:18.544917Z","time spent":"3.053868423s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:21.936201Z","caller":"traceutil/trace.go:171","msg":"trace[572743860] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"327.924357ms","start":"2026-03-19T13:30:21.608244Z","end":"2026-03-19T13:30:21.936168Z","steps":["trace[572743860] 'read index received' (duration: 205.711316ms)","trace[572743860] 'applied index is now lower than readState.Index' (duration: 122.211311ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:21.936253Z","caller":"traceutil/trace.go:171","msg":"trace[723435714] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"330.152771ms","start":"2026-03-19T13:30:21.606064Z","end":"2026-03-19T13:30:21.936217Z","steps":["trace[723435714] 'process raft request' (duration: 329.911941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.936473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.198517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:30:21.936478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:21.60604Z","time spent":"330.362521ms","remote":"127.0.0.1:53352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:30:21.936533Z","caller":"traceutil/trace.go:171","msg":"trace[1910234665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"328.296127ms","start":"2026-03-19T13:30:21.608222Z","end":"2026-03-19T13:30:21.936518Z","steps":["trace[1910234665] 'agreement among raft nodes before linearized reading' (duration: 328.070677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:21.936585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:21.608216Z","time spent":"328.359047ms","remote":"127.0.0.1:53278","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-03-19T13:30:22.539177Z","caller":"traceutil/trace.go:171","msg":"trace[226918373] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"146.155419ms","start":"2026-03-19T13:30:22.392992Z","end":"2026-03-19T13:30:22.539148Z","steps":["trace[226918373] 'process raft request' (duration: 145.962519ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:23.836063Z","caller":"traceutil/trace.go:171","msg":"trace[156836222] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"227.602119ms","start":"2026-03-19T13:30:23.608431Z","end":"2026-03-19T13:30:23.836033Z","steps":["trace[156836222] 'process raft request' (duration: 227.382019ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:23.836952Z","caller":"traceutil/trace.go:171","msg":"trace[214838994] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.730459ms","start":"2026-03-19T13:30:23.609198Z","end":"2026-03-19T13:30:23.836928Z","steps":["trace[214838994] 'process raft request' (duration: 227.551048ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:23.837588Z","caller":"traceutil/trace.go:171","msg":"trace[619219821] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:543; }","duration":"162.791031ms","start":"2026-03-19T13:30:23.674773Z","end":"2026-03-19T13:30:23.837564Z","steps":["trace[619219821] 'read index received' (duration: 161.155428ms)","trace[619219821] 'applied index is now lower than readState.Index' (duration: 1.634453ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:23.837727Z","caller":"traceutil/trace.go:171","msg":"trace[1747270377] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"226.946187ms","start":"2026-03-19T13:30:23.610756Z","end":"2026-03-19T13:30:23.837702Z","steps":["trace[1747270377] 'process raft request' (duration: 226.711566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:23.83781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.019041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:23.838576Z","caller":"traceutil/trace.go:171","msg":"trace[227638158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"163.816933ms","start":"2026-03-19T13:30:23.674744Z","end":"2026-03-19T13:30:23.838561Z","steps":["trace[227638158] 'agreement among raft nodes before linearized reading' (duration: 163.020851ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:23.8376Z","caller":"traceutil/trace.go:171","msg":"trace[919283486] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"227.68123ms","start":"2026-03-19T13:30:23.609891Z","end":"2026-03-19T13:30:23.837572Z","steps":["trace[919283486] 'process raft request' (duration: 227.001969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:26.058371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.518123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-19T13:30:26.058414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.559858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T13:30:26.058492Z","caller":"traceutil/trace.go:171","msg":"trace[358559978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"217.691759ms","start":"2026-03-19T13:30:25.840782Z","end":"2026-03-19T13:30:26.058474Z","steps":["trace[358559978] 'range keys from in-memory index tree' (duration: 217.302537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:26.058533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.069268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T13:30:26.058568Z","caller":"traceutil/trace.go:171","msg":"trace[1749288293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"217.164448ms","start":"2026-03-19T13:30:25.841393Z","end":"2026-03-19T13:30:26.058558Z","steps":["trace[1749288293] 'range keys from in-memory index tree' (duration: 216.967268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:26.058569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.390122ms","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-03-19T13:30:26.058649Z","caller":"traceutil/trace.go:171","msg":"trace[1243801058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"214.517222ms","start":"2026-03-19T13:30:25.844114Z","end":"2026-03-19T13:30:26.058631Z","steps":["trace[1243801058] 'range keys from in-memory index tree' (duration: 214.117692ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:26.058446Z","caller":"traceutil/trace.go:171","msg":"trace[1848161266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"214.650253ms","start":"2026-03-19T13:30:25.843776Z","end":"2026-03-19T13:30:26.058426Z","steps":["trace[1848161266] 'range keys from in-memory index tree' (duration: 214.319442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:26.058718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.533987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T13:30:26.058752Z","caller":"traceutil/trace.go:171","msg":"trace[1480459028] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:536; }","duration":"100.598669ms","start":"2026-03-19T13:30:25.958144Z","end":"2026-03-19T13:30:26.058743Z","steps":["trace[1480459028] 'range keys from in-memory index tree' (duration: 100.457137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:29.122578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.61132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-19T13:30:29.122664Z","caller":"traceutil/trace.go:171","msg":"trace[810930707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"426.749531ms","start":"2026-03-19T13:30:28.695895Z","end":"2026-03-19T13:30:29.122645Z","steps":["trace[810930707] 'range keys from in-memory index tree' (duration: 426.44906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:29.122706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:28.695877Z","time spent":"426.820301ms","remote":"127.0.0.1:53330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-03-19T13:30:29.122579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.645548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:29.12322Z","caller":"traceutil/trace.go:171","msg":"trace[1268341240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"446.299359ms","start":"2026-03-19T13:30:28.676826Z","end":"2026-03-19T13:30:29.123125Z","steps":["trace[1268341240] 'range keys from in-memory index tree' (duration: 445.522577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:29.123353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:28.676781Z","time spent":"446.552149ms","remote":"127.0.0.1:53278","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-03-19T13:30:29.165443Z","caller":"traceutil/trace.go:171","msg":"trace[2060815640] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"269.912231ms","start":"2026-03-19T13:30:28.895505Z","end":"2026-03-19T13:30:29.165418Z","steps":["trace[2060815640] 'read index received' (duration: 269.722091ms)","trace[2060815640] 'applied index is now lower than readState.Index' (duration: 189.37µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:29.16587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.346683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-19T13:30:29.165921Z","caller":"traceutil/trace.go:171","msg":"trace[782612279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:593; }","duration":"270.435983ms","start":"2026-03-19T13:30:28.89547Z","end":"2026-03-19T13:30:29.165906Z","steps":["trace[782612279] 'agreement among raft nodes before linearized reading' (duration: 270.273173ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:29.166019Z","caller":"traceutil/trace.go:171","msg":"trace[1663090283] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"275.360183ms","start":"2026-03-19T13:30:28.890562Z","end":"2026-03-19T13:30:29.165923Z","steps":["trace[1663090283] 'process raft request' (duration: 274.732831ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:35.822686Z","caller":"traceutil/trace.go:171","msg":"trace[413969047] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"147.34921ms","start":"2026-03-19T13:30:35.675299Z","end":"2026-03-19T13:30:35.822648Z","steps":["trace[413969047] 'read index received' (duration: 146.783819ms)","trace[413969047] 'applied index is now lower than readState.Index' (duration: 563.661µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:35.822897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.53729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:35.822942Z","caller":"traceutil/trace.go:171","msg":"trace[185955294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"147.67609ms","start":"2026-03-19T13:30:35.675254Z","end":"2026-03-19T13:30:35.82293Z","steps":["trace[185955294] 'agreement among raft nodes before linearized reading' (duration: 147.54144ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:35.823556Z","caller":"traceutil/trace.go:171","msg":"trace[2074565962] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"307.590505ms","start":"2026-03-19T13:30:35.515327Z","end":"2026-03-19T13:30:35.822918Z","steps":["trace[2074565962] 'process raft request' (duration: 306.948734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:35.823828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:35.515283Z","time spent":"308.454017ms","remote":"127.0.0.1:53424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:36.15625Z","caller":"traceutil/trace.go:171","msg":"trace[1829820031] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"125.732316ms","start":"2026-03-19T13:30:36.030489Z","end":"2026-03-19T13:30:36.156222Z","steps":["trace[1829820031] 'read index received' (duration: 125.526516ms)","trace[1829820031] 'applied index is now lower than readState.Index' (duration: 204.66µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:36.156555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.047878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:36.156649Z","caller":"traceutil/trace.go:171","msg":"trace[1620382320] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:651; }","duration":"126.188858ms","start":"2026-03-19T13:30:36.030438Z","end":"2026-03-19T13:30:36.156627Z","steps":["trace[1620382320] 'agreement among raft nodes before linearized reading' (duration: 126.019708ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:36.156909Z","caller":"traceutil/trace.go:171","msg":"trace[1835369213] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"309.688729ms","start":"2026-03-19T13:30:35.847156Z","end":"2026-03-19T13:30:36.156845Z","steps":["trace[1835369213] 'process raft request' (duration: 308.875297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:36.157154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:35.847132Z","time spent":"309.885219ms","remote":"127.0.0.1:53424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:30:36.307926Z","caller":"traceutil/trace.go:171","msg":"trace[1781898913] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"145.876137ms","start":"2026-03-19T13:30:36.162022Z","end":"2026-03-19T13:30:36.307898Z","steps":["trace[1781898913] 'process raft request' (duration: 145.785317ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:36.307932Z","caller":"traceutil/trace.go:171","msg":"trace[218304191] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"145.929467ms","start":"2026-03-19T13:30:36.161975Z","end":"2026-03-19T13:30:36.307904Z","steps":["trace[218304191] 'process raft request' (duration: 145.590827ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:36.308118Z","caller":"traceutil/trace.go:171","msg":"trace[548450722] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:674; }","duration":"136.93069ms","start":"2026-03-19T13:30:36.171163Z","end":"2026-03-19T13:30:36.308094Z","steps":["trace[548450722] 'read index received' (duration: 48.202516ms)","trace[548450722] 'applied index is now lower than readState.Index' (duration: 88.726784ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:36.308167Z","caller":"traceutil/trace.go:171","msg":"trace[244568732] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"139.869896ms","start":"2026-03-19T13:30:36.168258Z","end":"2026-03-19T13:30:36.308128Z","steps":["trace[244568732] 'process raft request' (duration: 139.702596ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:36.308238Z","caller":"traceutil/trace.go:171","msg":"trace[39794247] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"145.063716ms","start":"2026-03-19T13:30:36.163163Z","end":"2026-03-19T13:30:36.308226Z","steps":["trace[39794247] 'process raft request' (duration: 144.693235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:36.308305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.12178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T13:30:36.308343Z","caller":"traceutil/trace.go:171","msg":"trace[1791130385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"137.19519ms","start":"2026-03-19T13:30:36.171137Z","end":"2026-03-19T13:30:36.308332Z","steps":["trace[1791130385] 'agreement among raft nodes before linearized reading' (duration: 137.04997ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:36.308427Z","caller":"traceutil/trace.go:171","msg":"trace[2008179988] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"137.564021ms","start":"2026-03-19T13:30:36.170769Z","end":"2026-03-19T13:30:36.308333Z","steps":["trace[2008179988] 'process raft request' (duration: 137.271801ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:39.637781Z","caller":"traceutil/trace.go:171","msg":"trace[2013329498] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"117.562241ms","start":"2026-03-19T13:30:39.520191Z","end":"2026-03-19T13:30:39.637754Z","steps":["trace[2013329498] 'process raft request' (duration: 115.329547ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:39.79349Z","caller":"traceutil/trace.go:171","msg":"trace[1909923467] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"121.432848ms","start":"2026-03-19T13:30:39.672029Z","end":"2026-03-19T13:30:39.793461Z","steps":["trace[1909923467] 'read index received' (duration: 116.396959ms)","trace[1909923467] 'applied index is now lower than readState.Index' (duration: 5.034239ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:39.793588Z","caller":"traceutil/trace.go:171","msg":"trace[1342739797] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"147.63334ms","start":"2026-03-19T13:30:39.645922Z","end":"2026-03-19T13:30:39.793555Z","steps":["trace[1342739797] 'process raft request' (duration: 142.59285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:39.793668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.601269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:39.793704Z","caller":"traceutil/trace.go:171","msg":"trace[127829454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"121.72087ms","start":"2026-03-19T13:30:39.671974Z","end":"2026-03-19T13:30:39.793695Z","steps":["trace[127829454] 'agreement among raft nodes before linearized reading' (duration: 121.5844ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:40.556914Z","caller":"traceutil/trace.go:171","msg":"trace[1623762261] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"164.153294ms","start":"2026-03-19T13:30:40.392739Z","end":"2026-03-19T13:30:40.556892Z","steps":["trace[1623762261] 'process raft request' (duration: 99.042335ms)","trace[1623762261] 'compare' (duration: 64.957537ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:40.558227Z","caller":"traceutil/trace.go:171","msg":"trace[284209521] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"165.451265ms","start":"2026-03-19T13:30:40.392762Z","end":"2026-03-19T13:30:40.558214Z","steps":["trace[284209521] 'process raft request' (duration: 164.097223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.866759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.419074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:30:44.866859Z","caller":"traceutil/trace.go:171","msg":"trace[1582999544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"296.546434ms","start":"2026-03-19T13:30:44.570292Z","end":"2026-03-19T13:30:44.866838Z","steps":["trace[1582999544] 'range keys from in-memory index tree' (duration: 296.234984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.867004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.352731ms","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-03-19T13:30:44.86709Z","caller":"traceutil/trace.go:171","msg":"trace[153680821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"295.486171ms","start":"2026-03-19T13:30:44.571583Z","end":"2026-03-19T13:30:44.867069Z","steps":["trace[153680821] 'range keys from in-memory index tree' (duration: 294.84522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.866567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.907048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:30:44.86755Z","caller":"traceutil/trace.go:171","msg":"trace[1206379776] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:726; }","duration":"269.95398ms","start":"2026-03-19T13:30:44.59758Z","end":"2026-03-19T13:30:44.867534Z","steps":["trace[1206379776] 'count revisions from in-memory index tree' (duration: 268.749628ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:44.883025Z","caller":"traceutil/trace.go:171","msg":"trace[793714474] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"208.43135ms","start":"2026-03-19T13:30:44.674574Z","end":"2026-03-19T13:30:44.883005Z","steps":["trace[793714474] 'read index received' (duration: 199.309093ms)","trace[793714474] 'applied index is now lower than readState.Index' (duration: 9.120697ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:30:44.883217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.59457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:44.883421Z","caller":"traceutil/trace.go:171","msg":"trace[1145446361] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:727; }","duration":"208.829092ms","start":"2026-03-19T13:30:44.674563Z","end":"2026-03-19T13:30:44.883392Z","steps":["trace[1145446361] 'agreement among raft nodes before linearized reading' (duration: 208.54524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.884459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.969261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:44.884533Z","caller":"traceutil/trace.go:171","msg":"trace[1182792990] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:728; }","duration":"209.128521ms","start":"2026-03-19T13:30:44.675382Z","end":"2026-03-19T13:30:44.884511Z","steps":["trace[1182792990] 'agreement among raft nodes before linearized reading' (duration: 208.988571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.884668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.926293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:44.884724Z","caller":"traceutil/trace.go:171","msg":"trace[628133453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"210.040153ms","start":"2026-03-19T13:30:44.67467Z","end":"2026-03-19T13:30:44.88471Z","steps":["trace[628133453] 'agreement among raft nodes before linearized reading' (duration: 209.825202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:44.889019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.311322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:30:44.889066Z","caller":"traceutil/trace.go:171","msg":"trace[1407186996] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:728; }","duration":"214.397502ms","start":"2026-03-19T13:30:44.674655Z","end":"2026-03-19T13:30:44.889053Z","steps":["trace[1407186996] 'agreement among raft nodes before linearized reading' (duration: 209.463291ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:46.432471Z","caller":"traceutil/trace.go:171","msg":"trace[1870542992] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"113.532794ms","start":"2026-03-19T13:30:46.318766Z","end":"2026-03-19T13:30:46.432299Z","steps":["trace[1870542992] 'process raft request' (duration: 113.325843ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:48.550195Z","caller":"traceutil/trace.go:171","msg":"trace[420648107] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"105.694079ms","start":"2026-03-19T13:30:48.44446Z","end":"2026-03-19T13:30:48.550154Z","steps":["trace[420648107] 'process raft request' (duration: 105.438488ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:48.575516Z","caller":"traceutil/trace.go:171","msg":"trace[369767679] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"127.35157ms","start":"2026-03-19T13:30:48.448131Z","end":"2026-03-19T13:30:48.575483Z","steps":["trace[369767679] 'process raft request' (duration: 126.948679ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:33.093338Z","caller":"traceutil/trace.go:171","msg":"trace[652284856] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"218.542889ms","start":"2026-03-19T13:31:32.874762Z","end":"2026-03-19T13:31:33.093305Z","steps":["trace[652284856] 'process raft request' (duration: 218.348418ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:33.095974Z","caller":"traceutil/trace.go:171","msg":"trace[1332918840] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"220.614862ms","start":"2026-03-19T13:31:32.875339Z","end":"2026-03-19T13:31:33.095954Z","steps":["trace[1332918840] 'process raft request' (duration: 220.489342ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:33.096155Z","caller":"traceutil/trace.go:171","msg":"trace[1798315934] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:898; }","duration":"165.054995ms","start":"2026-03-19T13:31:32.93106Z","end":"2026-03-19T13:31:33.096115Z","steps":["trace[1798315934] 'read index received' (duration: 162.016988ms)","trace[1798315934] 'applied index is now lower than readState.Index' (duration: 3.035727ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:33.096193Z","caller":"traceutil/trace.go:171","msg":"trace[321808446] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"220.836834ms","start":"2026-03-19T13:31:32.875332Z","end":"2026-03-19T13:31:33.096169Z","steps":["trace[321808446] 'process raft request' (duration: 220.578682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:33.096438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.351635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"info","ts":"2026-03-19T13:31:33.096512Z","caller":"traceutil/trace.go:171","msg":"trace[1724241004] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:865; }","duration":"165.469295ms","start":"2026-03-19T13:31:32.931017Z","end":"2026-03-19T13:31:33.096486Z","steps":["trace[1724241004] 'agreement among raft nodes before linearized reading' (duration: 165.240125ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:33.618739Z","caller":"traceutil/trace.go:171","msg":"trace[486632734] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"279.171389ms","start":"2026-03-19T13:31:33.33954Z","end":"2026-03-19T13:31:33.618711Z","steps":["trace[486632734] 'read index received' (duration: 278.858418ms)","trace[486632734] 'applied index is now lower than readState.Index' (duration: 311.781µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:33.618876Z","caller":"traceutil/trace.go:171","msg":"trace[2034155289] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"289.317789ms","start":"2026-03-19T13:31:33.329515Z","end":"2026-03-19T13:31:33.618832Z","steps":["trace[2034155289] 'process raft request' (duration: 289.026528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:33.618964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.40595ms","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-03-19T13:31:33.619021Z","caller":"traceutil/trace.go:171","msg":"trace[1102031263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:866; }","duration":"279.50324ms","start":"2026-03-19T13:31:33.339504Z","end":"2026-03-19T13:31:33.619007Z","steps":["trace[1102031263] 'agreement among raft nodes before linearized reading' (duration: 279.31897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:34.521825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.763955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:31:34.522081Z","caller":"traceutil/trace.go:171","msg":"trace[1340501158] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"898.998754ms","start":"2026-03-19T13:31:33.623042Z","end":"2026-03-19T13:31:34.522041Z","steps":["trace[1340501158] 'process raft request' (duration: 727.893648ms)","trace[1340501158] 'compare' (duration: 170.560085ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:34.522392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:33.623022Z","time spent":"899.182034ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:34.570756Z","caller":"traceutil/trace.go:171","msg":"trace[1635265288] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:902; }","duration":"155.873047ms","start":"2026-03-19T13:31:34.414857Z","end":"2026-03-19T13:31:34.57073Z","steps":["trace[1635265288] 'read index received' (duration: 81.274371ms)","trace[1635265288] 'applied index is now lower than readState.Index' (duration: 74.597436ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:34.571054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.171547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"info","ts":"2026-03-19T13:31:34.571154Z","caller":"traceutil/trace.go:171","msg":"trace[372611987] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:867; }","duration":"156.330737ms","start":"2026-03-19T13:31:34.414807Z","end":"2026-03-19T13:31:34.571138Z","steps":["trace[372611987] 'agreement among raft nodes before linearized reading' (duration: 156.022647ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:34.571406Z","caller":"traceutil/trace.go:171","msg":"trace[215736259] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"126.451818ms","start":"2026-03-19T13:31:34.444924Z","end":"2026-03-19T13:31:34.571376Z","steps":["trace[215736259] 'process raft request' (duration: 125.712227ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:35.275359Z","caller":"traceutil/trace.go:171","msg":"trace[652808582] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"173.18782ms","start":"2026-03-19T13:31:35.102144Z","end":"2026-03-19T13:31:35.275332Z","steps":["trace[652808582] 'process raft request' (duration: 172.890779ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:35.305044Z","caller":"traceutil/trace.go:171","msg":"trace[818556083] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"201.051024ms","start":"2026-03-19T13:31:35.103971Z","end":"2026-03-19T13:31:35.305022Z","steps":["trace[818556083] 'process raft request' (duration: 201.000694ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:35.305175Z","caller":"traceutil/trace.go:171","msg":"trace[1870404508] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"201.448056ms","start":"2026-03-19T13:31:35.103676Z","end":"2026-03-19T13:31:35.305124Z","steps":["trace[1870404508] 'process raft request' (duration: 201.132056ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:35.752148Z","caller":"traceutil/trace.go:171","msg":"trace[408510779] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"124.580784ms","start":"2026-03-19T13:31:35.627537Z","end":"2026-03-19T13:31:35.752118Z","steps":["trace[408510779] 'process raft request' (duration: 124.356163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:40.835064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.167773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:31:40.835172Z","caller":"traceutil/trace.go:171","msg":"trace[1300186924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"159.368263ms","start":"2026-03-19T13:31:40.675779Z","end":"2026-03-19T13:31:40.835147Z","steps":["trace[1300186924] 'range keys from in-memory index tree' (duration: 158.986052ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:52.789832Z","caller":"traceutil/trace.go:171","msg":"trace[1173565613] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"134.432364ms","start":"2026-03-19T13:31:52.655333Z","end":"2026-03-19T13:31:52.789765Z","steps":["trace[1173565613] 'process raft request' (duration: 134.289824ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:52.790101Z","caller":"traceutil/trace.go:171","msg":"trace[167135431] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:996; }","duration":"113.739264ms","start":"2026-03-19T13:31:52.676345Z","end":"2026-03-19T13:31:52.790084Z","steps":["trace[167135431] 'read index received' (duration: 113.733454ms)","trace[167135431] 'applied index is now lower than readState.Index' (duration: 4.79µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:52.790232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.865424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:31:52.790302Z","caller":"traceutil/trace.go:171","msg":"trace[1723233974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"113.999765ms","start":"2026-03-19T13:31:52.67629Z","end":"2026-03-19T13:31:52.79029Z","steps":["trace[1723233974] 'agreement among raft nodes before linearized reading' (duration: 113.890244ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:53.510718Z","caller":"traceutil/trace.go:171","msg":"trace[2092667806] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"134.017602ms","start":"2026-03-19T13:31:53.376662Z","end":"2026-03-19T13:31:53.51068Z","steps":["trace[2092667806] 'process raft request' (duration: 133.761522ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:54.053966Z","caller":"traceutil/trace.go:171","msg":"trace[1068609234] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"117.183229ms","start":"2026-03-19T13:31:53.936745Z","end":"2026-03-19T13:31:54.053929Z","steps":["trace[1068609234] 'process raft request' (duration: 116.996459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:55.220547Z","caller":"traceutil/trace.go:171","msg":"trace[452149007] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"126.105258ms","start":"2026-03-19T13:31:55.094411Z","end":"2026-03-19T13:31:55.220516Z","steps":["trace[452149007] 'read index received' (duration: 125.844217ms)","trace[452149007] 'applied index is now lower than readState.Index' (duration: 259.841µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:55.220714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.284668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:31:55.220751Z","caller":"traceutil/trace.go:171","msg":"trace[808486436] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:961; }","duration":"126.371209ms","start":"2026-03-19T13:31:55.094367Z","end":"2026-03-19T13:31:55.220739Z","steps":["trace[808486436] 'agreement among raft nodes before linearized reading' (duration: 126.257788ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:55.220834Z","caller":"traceutil/trace.go:171","msg":"trace[1592272817] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"210.046133ms","start":"2026-03-19T13:31:55.010688Z","end":"2026-03-19T13:31:55.220734Z","steps":["trace[1592272817] 'process raft request' (duration: 209.641041ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:56.08416Z","caller":"traceutil/trace.go:171","msg":"trace[1064499883] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"537.505225ms","start":"2026-03-19T13:31:55.546617Z","end":"2026-03-19T13:31:56.084123Z","steps":["trace[1064499883] 'process raft request' (duration: 469.149702ms)","trace[1064499883] 'compare' (duration: 68.193713ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:56.084399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:55.546595Z","time spent":"537.676856ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:56.096232Z","caller":"traceutil/trace.go:171","msg":"trace[1136872303] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"419.827524ms","start":"2026-03-19T13:31:55.676366Z","end":"2026-03-19T13:31:56.096194Z","steps":["trace[1136872303] 'read index received' (duration: 339.430327ms)","trace[1136872303] 'applied index is now lower than readState.Index' (duration: 80.396227ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:56.096329Z","caller":"traceutil/trace.go:171","msg":"trace[990293712] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"548.312776ms","start":"2026-03-19T13:31:55.547963Z","end":"2026-03-19T13:31:56.096275Z","steps":["trace[990293712] 'process raft request' (duration: 548.039336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:56.096465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.063705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:31:56.096507Z","caller":"traceutil/trace.go:171","msg":"trace[347064678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"420.204775ms","start":"2026-03-19T13:31:55.676291Z","end":"2026-03-19T13:31:56.096496Z","steps":["trace[347064678] 'agreement among raft nodes before linearized reading' (duration: 420.091305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:56.096499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.63276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:31:56.09653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:55.547953Z","time spent":"548.475857ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:56.096544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:55.676259Z","time spent":"420.277555ms","remote":"127.0.0.1:53278","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-03-19T13:31:56.096573Z","caller":"traceutil/trace.go:171","msg":"trace[1085564270] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:965; }","duration":"137.77454ms","start":"2026-03-19T13:31:55.95878Z","end":"2026-03-19T13:31:56.096554Z","steps":["trace[1085564270] 'agreement among raft nodes before linearized reading' (duration: 137.6418ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:57.028811Z","caller":"traceutil/trace.go:171","msg":"trace[1872973783] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"185.751405ms","start":"2026-03-19T13:31:56.843024Z","end":"2026-03-19T13:31:57.028776Z","steps":["trace[1872973783] 'process raft request' (duration: 185.570345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:15.766872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.888605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"warn","ts":"2026-03-19T13:32:15.767073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.077394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:32:15.76718Z","caller":"traceutil/trace.go:171","msg":"trace[1699481997] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1023; }","duration":"232.216096ms","start":"2026-03-19T13:32:15.534941Z","end":"2026-03-19T13:32:15.767157Z","steps":["trace[1699481997] 'range keys from in-memory index tree' (duration: 231.788765ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:32:15.767226Z","caller":"traceutil/trace.go:171","msg":"trace[1548818080] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1023; }","duration":"201.288395ms","start":"2026-03-19T13:32:15.565904Z","end":"2026-03-19T13:32:15.767192Z","steps":["trace[1548818080] 'range keys from in-memory index tree' (duration: 200.988544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:15.766904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.63769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:32:15.767475Z","caller":"traceutil/trace.go:171","msg":"trace[2115669322] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:1023; }","duration":"250.203381ms","start":"2026-03-19T13:32:15.517208Z","end":"2026-03-19T13:32:15.767412Z","steps":["trace[2115669322] 'range keys from in-memory index tree' (duration: 249.54988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:15.767021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.417267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-03-19T13:32:15.767645Z","caller":"traceutil/trace.go:171","msg":"trace[703317680] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1023; }","duration":"249.036919ms","start":"2026-03-19T13:32:15.518577Z","end":"2026-03-19T13:32:15.767614Z","steps":["trace[703317680] 'range keys from in-memory index tree' (duration: 248.326207ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:32:19.281033Z","caller":"traceutil/trace.go:171","msg":"trace[1393973275] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"101.115538ms","start":"2026-03-19T13:32:19.179887Z","end":"2026-03-19T13:32:19.281003Z","steps":["trace[1393973275] 'process raft request' (duration: 96.034939ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:32:28.033543Z","caller":"traceutil/trace.go:171","msg":"trace[542593923] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"132.45517ms","start":"2026-03-19T13:32:27.901063Z","end":"2026-03-19T13:32:28.033518Z","steps":["trace[542593923] 'read index received' (duration: 132.275719ms)","trace[542593923] 'applied index is now lower than readState.Index' (duration: 178.241µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:32:28.033596Z","caller":"traceutil/trace.go:171","msg":"trace[1680789724] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"193.62592ms","start":"2026-03-19T13:32:27.839934Z","end":"2026-03-19T13:32:28.03356Z","steps":["trace[1680789724] 'process raft request' (duration: 193.432759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:28.033818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.742001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nrgz8\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-19T13:32:28.033864Z","caller":"traceutil/trace.go:171","msg":"trace[345993496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nrgz8; range_end:; response_count:1; response_revision:1057; }","duration":"132.842191ms","start":"2026-03-19T13:32:27.90101Z","end":"2026-03-19T13:32:28.033852Z","steps":["trace[345993496] 'agreement among raft nodes before linearized reading' (duration: 132.6136ms)"],"step_count":1}