{"level":"warn","ts":"2026-05-26T07:17:03.951968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T07:17:03.952074Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T07:17:03.952149Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T07:17:03.952159Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-26T07:17:03.952181Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T07:17:03.952893Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-26T07:17:03.952994Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T07:17:03.9568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.583168ms"} {"level":"info","ts":"2026-05-26T07:17:03.967567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-26T07:17:03.967705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T07:17:03.967747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-26T07:17:03.967772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T07:17:03.967783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-26T07:17:03.967834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-26T07:17:03.979052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T07:17:03.980416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T07:17:03.985735Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T07:17:03.988744Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T07:17:03.988981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T07:17:03.98917Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T07:17:03.989214Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T07:17:03.989223Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T07:17:03.99046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-26T07:17:03.991052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-26T07:17:03.995517Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T07:17:03.995715Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-26T07:17:03.995774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-26T07:17:03.996262Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T07:17:03.996305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T07:17:04.069068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T07:17:04.069221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T07:17:04.069281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-26T07:17:04.069326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-26T07:17:04.069368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-26T07:17:04.069487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-26T07:17:04.069544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-26T07:17:04.070467Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:17:04.071377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T07:17:04.071385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T07:17:04.07185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T07:17:04.072005Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T07:17:04.072062Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T07:17:04.074079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:17:04.074225Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:17:04.074307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:17:04.0761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-26T07:17:04.076477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T07:17:09.307742Z","caller":"traceutil/trace.go:171","msg":"trace[1845867766] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"312.891894ms","start":"2026-05-26T07:17:08.994822Z","end":"2026-05-26T07:17:09.307714Z","steps":["trace[1845867766] 'process raft request' (duration: 312.73729ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:09.307819Z","caller":"traceutil/trace.go:171","msg":"trace[1664411223] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:273; }","duration":"104.491403ms","start":"2026-05-26T07:17:09.203309Z","end":"2026-05-26T07:17:09.307801Z","steps":["trace[1664411223] 'read index received' (duration: 104.483363ms)","trace[1664411223] 'applied index is now lower than readState.Index' (duration: 6.47µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:17:09.308159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.840481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3690"} {"level":"info","ts":"2026-05-26T07:17:09.30821Z","caller":"traceutil/trace.go:171","msg":"trace[457295769] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:267; }","duration":"104.954024ms","start":"2026-05-26T07:17:09.203247Z","end":"2026-05-26T07:17:09.308201Z","steps":["trace[457295769] 'agreement among raft nodes before linearized reading' (duration: 104.608746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:17:09.308309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:17:08.994792Z","time spent":"313.043047ms","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T07:17:09.50727Z","caller":"traceutil/trace.go:171","msg":"trace[2065021495] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"195.62865ms","start":"2026-05-26T07:17:09.311572Z","end":"2026-05-26T07:17:09.507201Z","steps":["trace[2065021495] 'read index received' (duration: 97.862011ms)","trace[2065021495] 'applied index is now lower than readState.Index' (duration: 97.765459ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:17:09.507399Z","caller":"traceutil/trace.go:171","msg":"trace[860085612] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"237.486839ms","start":"2026-05-26T07:17:09.26986Z","end":"2026-05-26T07:17:09.507347Z","steps":["trace[860085612] 'process raft request' (duration: 139.352501ms)","trace[860085612] 'compare' (duration: 97.81807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:17:09.507481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.880507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:17:09.507526Z","caller":"traceutil/trace.go:171","msg":"trace[941540558] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:268; }","duration":"195.99227ms","start":"2026-05-26T07:17:09.311522Z","end":"2026-05-26T07:17:09.507514Z","steps":["trace[941540558] 'agreement among raft nodes before linearized reading' (duration: 195.868737ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:09.519565Z","caller":"traceutil/trace.go:171","msg":"trace[1206221085] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"203.570012ms","start":"2026-05-26T07:17:09.315951Z","end":"2026-05-26T07:17:09.519521Z","steps":["trace[1206221085] 'process raft request' (duration: 203.268625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:17:34.417319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.228821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:17:34.41742Z","caller":"traceutil/trace.go:171","msg":"trace[1721950921] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:511; }","duration":"121.532009ms","start":"2026-05-26T07:17:34.295865Z","end":"2026-05-26T07:17:34.417397Z","steps":["trace[1721950921] 'range keys from in-memory index tree' (duration: 121.110289ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:41.233233Z","caller":"traceutil/trace.go:171","msg":"trace[1163667974] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"252.2007ms","start":"2026-05-26T07:17:40.981Z","end":"2026-05-26T07:17:41.233201Z","steps":["trace[1163667974] 'process raft request' (duration: 251.590725ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:41.233339Z","caller":"traceutil/trace.go:171","msg":"trace[1966748917] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"203.589211ms","start":"2026-05-26T07:17:41.029709Z","end":"2026-05-26T07:17:41.233298Z","steps":["trace[1966748917] 'process raft request' (duration: 203.415907ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:56.585146Z","caller":"traceutil/trace.go:171","msg":"trace[1811989352] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"135.324794ms","start":"2026-05-26T07:17:56.449795Z","end":"2026-05-26T07:17:56.58512Z","steps":["trace[1811989352] 'read index received' (duration: 135.256422ms)","trace[1811989352] 'applied index is now lower than readState.Index' (duration: 67.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:17:56.585398Z","caller":"traceutil/trace.go:171","msg":"trace[249108235] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"161.657802ms","start":"2026-05-26T07:17:56.423722Z","end":"2026-05-26T07:17:56.58538Z","steps":["trace[249108235] 'process raft request' (duration: 161.252902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:17:56.58577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.956539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T07:17:56.585857Z","caller":"traceutil/trace.go:171","msg":"trace[906839658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"136.087692ms","start":"2026-05-26T07:17:56.44975Z","end":"2026-05-26T07:17:56.585837Z","steps":["trace[906839658] 'agreement among raft nodes before linearized reading' (duration: 135.911808ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:56.70017Z","caller":"traceutil/trace.go:171","msg":"trace[158306144] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"108.718488ms","start":"2026-05-26T07:17:56.591386Z","end":"2026-05-26T07:17:56.700104Z","steps":["trace[158306144] 'process raft request' (duration: 101.625016ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:17:56.70142Z","caller":"traceutil/trace.go:171","msg":"trace[223067328] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"109.562469ms","start":"2026-05-26T07:17:56.591832Z","end":"2026-05-26T07:17:56.701394Z","steps":["trace[223067328] 'process raft request' (duration: 108.185085ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:18:33.126503Z","caller":"traceutil/trace.go:171","msg":"trace[640316583] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"250.031731ms","start":"2026-05-26T07:18:32.876446Z","end":"2026-05-26T07:18:33.126478Z","steps":["trace[640316583] 'read index received' (duration: 7.551904ms)","trace[640316583] 'applied index is now lower than readState.Index' (duration: 242.478837ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:18:33.126542Z","caller":"traceutil/trace.go:171","msg":"trace[591239417] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"250.653215ms","start":"2026-05-26T07:18:32.875873Z","end":"2026-05-26T07:18:33.126526Z","steps":["trace[591239417] 'process raft request' (duration: 173.96097ms)","trace[591239417] 'compare' (duration: 76.538281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:18:33.126695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.231605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T07:18:33.12672Z","caller":"traceutil/trace.go:171","msg":"trace[596252514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"250.280006ms","start":"2026-05-26T07:18:32.876433Z","end":"2026-05-26T07:18:33.126713Z","steps":["trace[596252514] 'agreement among raft nodes before linearized reading' (duration: 250.142883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:18:33.385596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.531493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-26T07:18:33.385915Z","caller":"traceutil/trace.go:171","msg":"trace[1503254063] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"255.535013ms","start":"2026-05-26T07:18:33.130362Z","end":"2026-05-26T07:18:33.385897Z","steps":["trace[1503254063] 'process raft request' (duration: 255.39476ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:18:33.385941Z","caller":"traceutil/trace.go:171","msg":"trace[2110233393] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"259.332497ms","start":"2026-05-26T07:18:33.12658Z","end":"2026-05-26T07:18:33.385912Z","steps":["trace[2110233393] 'read index received' (duration: 8.337823ms)","trace[2110233393] 'applied index is now lower than readState.Index' (duration: 250.992164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:18:33.386073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.650945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:18:33.386106Z","caller":"traceutil/trace.go:171","msg":"trace[53320845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"426.729147ms","start":"2026-05-26T07:18:32.959367Z","end":"2026-05-26T07:18:33.386096Z","steps":["trace[53320845] 'agreement among raft nodes before linearized reading' (duration: 426.649805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:18:33.386139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:18:32.959351Z","time spent":"426.781018ms","remote":"127.0.0.1:45664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T07:18:33.386124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.014456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T07:18:33.386188Z","caller":"traceutil/trace.go:171","msg":"trace[2108255023] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1004; }","duration":"284.131689ms","start":"2026-05-26T07:18:33.102041Z","end":"2026-05-26T07:18:33.386173Z","steps":["trace[2108255023] 'agreement among raft nodes before linearized reading' (duration: 284.023736ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:18:54.963228Z","caller":"traceutil/trace.go:171","msg":"trace[1525048786] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"119.564227ms","start":"2026-05-26T07:18:54.843621Z","end":"2026-05-26T07:18:54.963185Z","steps":["trace[1525048786] 'process raft request' (duration: 119.351192ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:12.176745Z","caller":"traceutil/trace.go:171","msg":"trace[48954179] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"280.801029ms","start":"2026-05-26T07:19:11.895917Z","end":"2026-05-26T07:19:12.176718Z","steps":["trace[48954179] 'read index received' (duration: 280.516212ms)","trace[48954179] 'applied index is now lower than readState.Index' (duration: 270.167µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:19:12.17694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.992363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T07:19:12.176993Z","caller":"traceutil/trace.go:171","msg":"trace[1212728344] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1181; }","duration":"281.102926ms","start":"2026-05-26T07:19:11.895877Z","end":"2026-05-26T07:19:12.17698Z","steps":["trace[1212728344] 'agreement among raft nodes before linearized reading' (duration: 280.950653ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:12.177005Z","caller":"traceutil/trace.go:171","msg":"trace[497868691] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"328.57638ms","start":"2026-05-26T07:19:11.848407Z","end":"2026-05-26T07:19:12.176984Z","steps":["trace[497868691] 'process raft request' (duration: 328.099179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:19:12.177237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:19:11.84838Z","time spent":"328.681103ms","remote":"127.0.0.1:45960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:19:28.452965Z","caller":"traceutil/trace.go:171","msg":"trace[2010208981] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"224.489399ms","start":"2026-05-26T07:19:28.228442Z","end":"2026-05-26T07:19:28.452931Z","steps":["trace[2010208981] 'process raft request' (duration: 224.247893ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:28.610367Z","caller":"traceutil/trace.go:171","msg":"trace[935924221] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"375.083092ms","start":"2026-05-26T07:19:28.235266Z","end":"2026-05-26T07:19:28.610349Z","steps":["trace[935924221] 'process raft request' (duration: 374.545939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:19:28.610489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:19:28.235247Z","time spent":"375.183554ms","remote":"127.0.0.1:45858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:19:28.753812Z","caller":"traceutil/trace.go:171","msg":"trace[1730033559] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"136.275294ms","start":"2026-05-26T07:19:28.617302Z","end":"2026-05-26T07:19:28.753577Z","steps":["trace[1730033559] 'process raft request' (duration: 74.247605ms)","trace[1730033559] 'compare' (duration: 61.886615ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:19:29.128406Z","caller":"traceutil/trace.go:171","msg":"trace[292704418] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"162.902292ms","start":"2026-05-26T07:19:28.965477Z","end":"2026-05-26T07:19:29.128379Z","steps":["trace[292704418] 'read index received' (duration: 134.708856ms)","trace[292704418] 'applied index is now lower than readState.Index' (duration: 28.192126ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:19:29.128533Z","caller":"traceutil/trace.go:171","msg":"trace[2137889219] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"174.923784ms","start":"2026-05-26T07:19:28.953532Z","end":"2026-05-26T07:19:29.128456Z","steps":["trace[2137889219] 'process raft request' (duration: 146.683227ms)","trace[2137889219] 'compare' (duration: 28.002921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:19:29.128636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.127887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:19:29.128681Z","caller":"traceutil/trace.go:171","msg":"trace[835290191] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1257; }","duration":"163.22158ms","start":"2026-05-26T07:19:28.965446Z","end":"2026-05-26T07:19:29.128668Z","steps":["trace[835290191] 'agreement among raft nodes before linearized reading' (duration: 163.031015ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:29.422331Z","caller":"traceutil/trace.go:171","msg":"trace[1522234753] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"247.53603ms","start":"2026-05-26T07:19:29.174769Z","end":"2026-05-26T07:19:29.422305Z","steps":["trace[1522234753] 'process raft request' (duration: 247.351545ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:29.446292Z","caller":"traceutil/trace.go:171","msg":"trace[163450909] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"270.922358ms","start":"2026-05-26T07:19:29.175332Z","end":"2026-05-26T07:19:29.446254Z","steps":["trace[163450909] 'process raft request' (duration: 270.62675ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:29.446401Z","caller":"traceutil/trace.go:171","msg":"trace[661030741] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"264.140134ms","start":"2026-05-26T07:19:29.182229Z","end":"2026-05-26T07:19:29.446369Z","steps":["trace[661030741] 'process raft request' (duration: 263.912429ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:29.557407Z","caller":"traceutil/trace.go:171","msg":"trace[1410261989] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"106.880809ms","start":"2026-05-26T07:19:29.450497Z","end":"2026-05-26T07:19:29.557378Z","steps":["trace[1410261989] 'process raft request' (duration: 71.351266ms)","trace[1410261989] 'compare' (duration: 35.38094ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:19:30.061706Z","caller":"traceutil/trace.go:171","msg":"trace[562243719] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"290.905765ms","start":"2026-05-26T07:19:29.770769Z","end":"2026-05-26T07:19:30.061675Z","steps":["trace[562243719] 'process raft request' (duration: 290.71218ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:30.106667Z","caller":"traceutil/trace.go:171","msg":"trace[735224619] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"148.695985ms","start":"2026-05-26T07:19:29.957943Z","end":"2026-05-26T07:19:30.106639Z","steps":["trace[735224619] 'read index received' (duration: 103.528527ms)","trace[735224619] 'applied index is now lower than readState.Index' (duration: 45.166118ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:19:30.106784Z","caller":"traceutil/trace.go:171","msg":"trace[226194820] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"334.091155ms","start":"2026-05-26T07:19:29.772679Z","end":"2026-05-26T07:19:30.10677Z","steps":["trace[226194820] 'process raft request' (duration: 333.825818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:19:30.106927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:19:29.772666Z","time spent":"334.145546ms","remote":"127.0.0.1:45960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T07:19:30.107054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.090646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:19:30.107112Z","caller":"traceutil/trace.go:171","msg":"trace[1566346543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"149.163997ms","start":"2026-05-26T07:19:29.957933Z","end":"2026-05-26T07:19:30.107097Z","steps":["trace[1566346543] 'agreement among raft nodes before linearized reading' (duration: 148.931602ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:30.171362Z","caller":"traceutil/trace.go:171","msg":"trace[1551936754] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"105.194568ms","start":"2026-05-26T07:19:30.066102Z","end":"2026-05-26T07:19:30.171297Z","steps":["trace[1551936754] 'process raft request' (duration: 74.650456ms)","trace[1551936754] 'compare' (duration: 30.295206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:19:30.171619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.198551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:19:30.1717Z","caller":"traceutil/trace.go:171","msg":"trace[732385472] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:1269; }","duration":"117.335393ms","start":"2026-05-26T07:19:30.054348Z","end":"2026-05-26T07:19:30.171683Z","steps":["trace[732385472] 'agreement among raft nodes before linearized reading' (duration: 117.144228ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:35.759682Z","caller":"traceutil/trace.go:171","msg":"trace[1111251549] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"164.79739ms","start":"2026-05-26T07:19:35.594863Z","end":"2026-05-26T07:19:35.75966Z","steps":["trace[1111251549] 'read index received' (duration: 156.224772ms)","trace[1111251549] 'applied index is now lower than readState.Index' (duration: 8.571458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:19:35.75986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.980355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T07:19:35.759893Z","caller":"traceutil/trace.go:171","msg":"trace[108435127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"165.058256ms","start":"2026-05-26T07:19:35.594825Z","end":"2026-05-26T07:19:35.759883Z","steps":["trace[108435127] 'agreement among raft nodes before linearized reading' (duration: 164.910082ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:19:35.759933Z","caller":"traceutil/trace.go:171","msg":"trace[1462818624] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"265.266528ms","start":"2026-05-26T07:19:35.494651Z","end":"2026-05-26T07:19:35.759918Z","steps":["trace[1462818624] 'process raft request' (duration: 256.391262ms)"],"step_count":1}