{"level":"warn","ts":"2026-10-13T14:53:26.142366Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T14:53:26.142464Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.215: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://162.253.55.215:2380","--initial-cluster=instance=https://162.253.55.215:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.215:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.215: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-10-13T14:53:26.142546Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T14:53:26.14256Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.215:2380"]} {"level":"info","ts":"2026-10-13T14:53:26.142581Z","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-10-13T14:53:26.143426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"]} {"level":"info","ts":"2026-10-13T14:53:26.143525Z","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://162.253.55.215:2380"],"listen-peer-urls":["https://162.253.55.215:2380"],"advertise-client-urls":["https://162.253.55.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.215: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-10-13T14:53:26.152099Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.530049ms"} {"level":"info","ts":"2026-10-13T14:53:26.158185Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4b019b7871d5da9a","cluster-id":"608f860684155cc8"} {"level":"info","ts":"2026-10-13T14:53:26.158245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T14:53:26.158269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became follower at term 0"} {"level":"info","ts":"2026-10-13T14:53:26.158284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4b019b7871d5da9a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T14:53:26.158293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became follower at term 1"} {"level":"info","ts":"2026-10-13T14:53:26.158325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=(5404771969429527194)"} {"level":"warn","ts":"2026-10-13T14:53:26.163959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T14:53:26.165402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T14:53:26.168558Z","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-10-13T14:53:26.170158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4b019b7871d5da9a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T14:53:26.170624Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4b019b7871d5da9a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T14:53:26.170722Z","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-10-13T14:53:26.172944Z","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-10-13T14:53:26.173207Z","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-10-13T14:53:26.173494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=(5404771969429527194)"} {"level":"info","ts":"2026-10-13T14:53:26.175979Z","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-10-13T14:53:26.176224Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4b019b7871d5da9a","initial-advertise-peer-urls":["https://162.253.55.215:2380"],"listen-peer-urls":["https://162.253.55.215:2380"],"advertise-client-urls":["https://162.253.55.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T14:53:26.176271Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T14:53:26.176325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"608f860684155cc8","local-member-id":"4b019b7871d5da9a","added-peer-id":"4b019b7871d5da9a","added-peer-peer-urls":["https://162.253.55.215:2380"]} {"level":"info","ts":"2026-10-13T14:53:26.176465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.215:2380"} {"level":"info","ts":"2026-10-13T14:53:26.176491Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.215:2380"} {"level":"info","ts":"2026-10-13T14:53:26.360826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T14:53:26.360925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T14:53:26.360942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a received MsgPreVoteResp from 4b019b7871d5da9a at term 1"} {"level":"info","ts":"2026-10-13T14:53:26.360957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became candidate at term 2"} {"level":"info","ts":"2026-10-13T14:53:26.360965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a received MsgVoteResp from 4b019b7871d5da9a at term 2"} {"level":"info","ts":"2026-10-13T14:53:26.360985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became leader at term 2"} {"level":"info","ts":"2026-10-13T14:53:26.360994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4b019b7871d5da9a elected leader 4b019b7871d5da9a at term 2"} {"level":"info","ts":"2026-10-13T14:53:26.385537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:53:26.571819Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"608f860684155cc8","local-member-id":"4b019b7871d5da9a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:53:26.571926Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:53:26.571952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T14:53:26.571923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T14:53:26.571935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T14:53:26.572425Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T14:53:26.572554Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T14:53:26.574389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.215:2379"} {"level":"info","ts":"2026-10-13T14:53:26.577485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T14:53:26.579788Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4b019b7871d5da9a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.215:2379]}","request-path":"/0/members/4b019b7871d5da9a/attributes","cluster-id":"608f860684155cc8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T14:54:16.705006Z","caller":"traceutil/trace.go:171","msg":"trace[452473459] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"164.447117ms","start":"2026-10-13T14:54:16.540526Z","end":"2026-10-13T14:54:16.704973Z","steps":["trace[452473459] 'process raft request' (duration: 163.493809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:17.309303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.470462ms","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-10-13T14:54:17.309453Z","caller":"traceutil/trace.go:171","msg":"trace[1058462704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"127.651145ms","start":"2026-10-13T14:54:17.181778Z","end":"2026-10-13T14:54:17.309429Z","steps":["trace[1058462704] 'range keys from in-memory index tree' (duration: 127.298688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:17.309498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.386327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g678c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T14:54:17.309559Z","caller":"traceutil/trace.go:171","msg":"trace[1435640978] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g678c; range_end:; response_count:1; response_revision:605; }","duration":"130.462429ms","start":"2026-10-13T14:54:17.179076Z","end":"2026-10-13T14:54:17.309539Z","steps":["trace[1435640978] 'range keys from in-memory index tree' (duration: 130.254734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:54:17.30936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.984672ms","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-10-13T14:54:17.309971Z","caller":"traceutil/trace.go:171","msg":"trace[1209322239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"297.637893ms","start":"2026-10-13T14:54:17.012317Z","end":"2026-10-13T14:54:17.309955Z","steps":["trace[1209322239] 'range keys from in-memory index tree' (duration: 296.820079ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:54:34.466568Z","caller":"traceutil/trace.go:171","msg":"trace[1398647689] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"166.773324ms","start":"2026-10-13T14:54:34.299763Z","end":"2026-10-13T14:54:34.466537Z","steps":["trace[1398647689] 'process raft request' (duration: 166.509209ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:55:25.760128Z","caller":"traceutil/trace.go:171","msg":"trace[742579115] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"109.528854ms","start":"2026-10-13T14:55:25.650574Z","end":"2026-10-13T14:55:25.760103Z","steps":["trace[742579115] 'process raft request' (duration: 109.160178ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:55:50.13281Z","caller":"traceutil/trace.go:171","msg":"trace[1185213466] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"251.530052ms","start":"2026-10-13T14:55:49.881258Z","end":"2026-10-13T14:55:50.132788Z","steps":["trace[1185213466] 'read index received' (duration: 251.342089ms)","trace[1185213466] 'applied index is now lower than readState.Index' (duration: 187.233µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:55:50.132847Z","caller":"traceutil/trace.go:171","msg":"trace[176492659] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"263.976841ms","start":"2026-10-13T14:55:49.868853Z","end":"2026-10-13T14:55:50.13283Z","steps":["trace[176492659] 'process raft request' (duration: 263.802568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:55:50.133158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.755225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-13T14:55:50.133203Z","caller":"traceutil/trace.go:171","msg":"trace[1881444980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1831; }","duration":"252.10239ms","start":"2026-10-13T14:55:49.881092Z","end":"2026-10-13T14:55:50.133195Z","steps":["trace[1881444980] 'agreement among raft nodes before linearized reading' (duration: 251.827906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:55:50.133035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.267746ms","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-10-13T14:55:50.133276Z","caller":"traceutil/trace.go:171","msg":"trace[2099208687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1831; }","duration":"218.52898ms","start":"2026-10-13T14:55:49.914712Z","end":"2026-10-13T14:55:50.133241Z","steps":["trace[2099208687] 'agreement among raft nodes before linearized reading' (duration: 218.250676ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:55:50.237416Z","caller":"traceutil/trace.go:171","msg":"trace[1542334726] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"100.380744ms","start":"2026-10-13T14:55:50.137011Z","end":"2026-10-13T14:55:50.237392Z","steps":["trace[1542334726] 'process raft request' (duration: 100.272593ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:55:50.237493Z","caller":"traceutil/trace.go:171","msg":"trace[2100406880] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"100.392104ms","start":"2026-10-13T14:55:50.137005Z","end":"2026-10-13T14:55:50.237397Z","steps":["trace[2100406880] 'process raft request' (duration: 65.665698ms)","trace[2100406880] 'compare' (duration: 34.436852ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:55:50.549831Z","caller":"traceutil/trace.go:171","msg":"trace[449750683] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"101.41967ms","start":"2026-10-13T14:55:50.448394Z","end":"2026-10-13T14:55:50.549814Z","steps":["trace[449750683] 'read index received' (duration: 99.913316ms)","trace[449750683] 'applied index is now lower than readState.Index' (duration: 1.505424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:55:50.550153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.740754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T14:55:50.550195Z","caller":"traceutil/trace.go:171","msg":"trace[285714891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"101.820645ms","start":"2026-10-13T14:55:50.448364Z","end":"2026-10-13T14:55:50.550185Z","steps":["trace[285714891] 'agreement among raft nodes before linearized reading' (duration: 101.520021ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:55:50.550118Z","caller":"traceutil/trace.go:171","msg":"trace[1080270229] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"118.624441ms","start":"2026-10-13T14:55:50.431447Z","end":"2026-10-13T14:55:50.550071Z","steps":["trace[1080270229] 'process raft request' (duration: 116.824083ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:56:23.63575Z","caller":"traceutil/trace.go:171","msg":"trace[1505730613] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"118.961091ms","start":"2026-10-13T14:56:23.516748Z","end":"2026-10-13T14:56:23.635709Z","steps":["trace[1505730613] 'process raft request' (duration: 74.67076ms)","trace[1505730613] 'compare' (duration: 44.004867ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:57:27.936441Z","caller":"traceutil/trace.go:171","msg":"trace[825870973] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2616; }","duration":"125.036585ms","start":"2026-10-13T14:57:27.811379Z","end":"2026-10-13T14:57:27.936416Z","steps":["trace[825870973] 'read index received' (duration: 124.889251ms)","trace[825870973] 'applied index is now lower than readState.Index' (duration: 146.164µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T14:57:27.936562Z","caller":"traceutil/trace.go:171","msg":"trace[1549350213] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"148.259904ms","start":"2026-10-13T14:57:27.78828Z","end":"2026-10-13T14:57:27.93654Z","steps":["trace[1549350213] 'process raft request' (duration: 148.005505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:57:27.936639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.242913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T14:57:27.936668Z","caller":"traceutil/trace.go:171","msg":"trace[754297279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2523; }","duration":"125.294414ms","start":"2026-10-13T14:57:27.811365Z","end":"2026-10-13T14:57:27.93666Z","steps":["trace[754297279] 'agreement among raft nodes before linearized reading' (duration: 125.152369ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:58:00.275952Z","caller":"traceutil/trace.go:171","msg":"trace[1325156513] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"166.257964ms","start":"2026-10-13T14:58:00.109669Z","end":"2026-10-13T14:58:00.275927Z","steps":["trace[1325156513] 'process raft request' (duration: 166.107629ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:58:00.277001Z","caller":"traceutil/trace.go:171","msg":"trace[1186689240] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"139.904096ms","start":"2026-10-13T14:58:00.137069Z","end":"2026-10-13T14:58:00.276973Z","steps":["trace[1186689240] 'read index received' (duration: 138.884007ms)","trace[1186689240] 'applied index is now lower than readState.Index' (duration: 1.018889ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:58:00.27742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.290297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-13T14:58:00.277825Z","caller":"traceutil/trace.go:171","msg":"trace[1948389926] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"164.975997ms","start":"2026-10-13T14:58:00.112821Z","end":"2026-10-13T14:58:00.277797Z","steps":["trace[1948389926] 'process raft request' (duration: 164.028159ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T14:58:00.277879Z","caller":"traceutil/trace.go:171","msg":"trace[1393949483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2703; }","duration":"140.450742ms","start":"2026-10-13T14:58:00.137043Z","end":"2026-10-13T14:58:00.277494Z","steps":["trace[1393949483] 'agreement among raft nodes before linearized reading' (duration: 140.205035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T14:58:05.763268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.814ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-13T14:58:05.763368Z","caller":"traceutil/trace.go:171","msg":"trace[2008028641] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2832; }","duration":"164.683521ms","start":"2026-10-13T14:58:05.598667Z","end":"2026-10-13T14:58:05.76335Z","steps":["trace[2008028641] 'read index received' (duration: 25.701446ms)","trace[2008028641] 'applied index is now lower than readState.Index' (duration: 138.980805ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T14:58:05.763527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.855376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-13T14:58:05.763561Z","caller":"traceutil/trace.go:171","msg":"trace[945866684] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2730; }","duration":"164.931978ms","start":"2026-10-13T14:58:05.59862Z","end":"2026-10-13T14:58:05.763552Z","steps":["trace[945866684] 'agreement among raft nodes before linearized reading' (duration: 164.773614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:33.11411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.379339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-10-13T15:02:33.114311Z","caller":"traceutil/trace.go:171","msg":"trace[180749677] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4889; }","duration":"123.577823ms","start":"2026-10-13T15:02:32.990686Z","end":"2026-10-13T15:02:33.114263Z","steps":["trace[180749677] 'range keys from in-memory index tree' (duration: 122.489233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:43.558209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.896136ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:02:43.558382Z","caller":"traceutil/trace.go:171","msg":"trace[381502254] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5058; }","duration":"143.10547ms","start":"2026-10-13T15:02:43.415253Z","end":"2026-10-13T15:02:43.558359Z","steps":["trace[381502254] 'range keys from in-memory index tree' (duration: 142.872565ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:47.939374Z","caller":"traceutil/trace.go:171","msg":"trace[1389115086] linearizableReadLoop","detail":"{readStateIndex:5285; appliedIndex:5285; }","duration":"125.741595ms","start":"2026-10-13T15:02:47.813613Z","end":"2026-10-13T15:02:47.939355Z","steps":["trace[1389115086] 'read index received' (duration: 125.731175ms)","trace[1389115086] 'applied index is now lower than readState.Index' (duration: 8.63µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:02:47.939625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.990459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-13T15:02:47.939355Z","caller":"traceutil/trace.go:171","msg":"trace[963904544] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"125.709534ms","start":"2026-10-13T15:02:47.813592Z","end":"2026-10-13T15:02:47.939302Z","steps":["trace[963904544] 'process raft request' (duration: 125.552431ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:47.939681Z","caller":"traceutil/trace.go:171","msg":"trace[19927386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5091; }","duration":"126.094671ms","start":"2026-10-13T15:02:47.813572Z","end":"2026-10-13T15:02:47.939667Z","steps":["trace[19927386] 'agreement among raft nodes before linearized reading' (duration: 125.910677ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:48.260331Z","caller":"traceutil/trace.go:171","msg":"trace[2075750233] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"396.740071ms","start":"2026-10-13T15:02:47.863534Z","end":"2026-10-13T15:02:48.260274Z","steps":["trace[2075750233] 'process raft request' (duration: 396.620519ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:48.260759Z","caller":"traceutil/trace.go:171","msg":"trace[2051340744] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5286; }","duration":"321.278112ms","start":"2026-10-13T15:02:47.939457Z","end":"2026-10-13T15:02:48.260735Z","steps":["trace[2051340744] 'read index received' (duration: 321.271532ms)","trace[2051340744] 'applied index is now lower than readState.Index' (duration: 4.97µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:02:48.26092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:47.863512Z","time spent":"396.922674ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:02:48.261039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.713456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-10-13T15:02:48.261074Z","caller":"traceutil/trace.go:171","msg":"trace[1758576919] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5092; }","duration":"380.806178ms","start":"2026-10-13T15:02:47.880258Z","end":"2026-10-13T15:02:48.261064Z","steps":["trace[1758576919] 'agreement among raft nodes before linearized reading' (duration: 380.634485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:48.261104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:47.880238Z","time spent":"380.858618ms","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10417,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-13T15:02:48.267779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.502127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-13T15:02:48.267839Z","caller":"traceutil/trace.go:171","msg":"trace[537490162] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5093; }","duration":"320.600549ms","start":"2026-10-13T15:02:47.947223Z","end":"2026-10-13T15:02:48.267823Z","steps":["trace[537490162] 'agreement among raft nodes before linearized reading' (duration: 320.427016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:48.267873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:47.9472Z","time spent":"320.664859ms","remote":"127.0.0.1:39718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-13T15:02:48.268116Z","caller":"traceutil/trace.go:171","msg":"trace[922917573] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"320.859344ms","start":"2026-10-13T15:02:47.947244Z","end":"2026-10-13T15:02:48.268104Z","steps":["trace[922917573] 'process raft request' (duration: 320.320983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:48.268182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:47.947227Z","time spent":"320.911465ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:03:26.756448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-10-13T15:03:26.827251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"69.756201ms","hash":288937290,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":28377088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T15:03:26.827348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":288937290,"revision":2841,"compact-revision":-1} {"level":"info","ts":"2026-10-13T15:08:26.761507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5423} {"level":"info","ts":"2026-10-13T15:08:26.837591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5423,"took":"74.526447ms","hash":2164375651,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":18366464,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-13T15:08:26.837664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2164375651,"revision":5423,"compact-revision":2841}