Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2022 19:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15064040 kB
MemAvailable 30111136 kB
Buffers 0 kB
Cached 16670776 kB
SwapCached 3216 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-24 19:38:35
DTS_END                         2022-12-24 19:38:35.3700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34566
TRN_ID                          240235
ATT_ID                          642
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9009.61 23240 1081153
customer order: creation 164.32 361 19718
customer order: refuse 13.91 325 1669
order to supplier: creation 42.57 1223 5108
order to supplier: removal 3.25 107648 390
invoice (draft): creation 43.90 3319 5268
invoice (draft): removal 51.79 771 6215
invoice accept: apply 39.83 19013 4780
invoice accept: cancel 46.60 765 5592
customer reserve: creation 3712.53 187 445504
customer reserve: removal 4605.22 60 552626
realization accept: apply 81.88 193 9825
realization accept: cancel 80.31 107 9637
payment from customer: creation 56.96 138 6835
payment from customer: removal 5.28 95 633
payment to supplier: creation 46.83 166 5620
payment to supplier: removal 2.98 68 358
service: total inventory turnovers 4.78 903 574
service: total monetary turnovers 5.88 30 705
service: aggregate perf. data 0.77 23048 92
service: refresh index statistics 0.03 306384 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-12-2022 19:40:59 to 24-12-2022 19:41:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1449
TEST_TIME 2 2050
TEST_TIME 3 3597
TEST_TIME 4 4391
TEST_TIME 5 4292
TEST_TIME 6 4189
TEST_TIME 7 4331
TEST_TIME 8 4652
TEST_TIME 9 4814
TEST_TIME 10 5151
TEST_TIME 11 5573
TEST_TIME 12 6250
TEST_TIME 13 6372
TEST_TIME 14 6512
TEST_TIME 15 6667
TEST_TIME 16 6756
TEST_TIME 17 6730
TEST_TIME 18 6966
TEST_TIME 19 7455
TEST_TIME 20 8402
TEST_TIME 21 8353
TEST_TIME 22 8685
TEST_TIME 23 9083
TEST_TIME 24 9285
TEST_TIME 25 9691
TEST_TIME 26 9858
TEST_TIME 27 10798
TEST_TIME 28 10810
TEST_TIME 29 10778
TEST_TIME 30 10816
TEST_TIME 31 10611
TEST_TIME 32 10579
TEST_TIME 33 10341
TEST_TIME 34 10188
TEST_TIME 35 10250
TEST_TIME 36 10233
TEST_TIME 37 10201
TEST_TIME 38 10040
TEST_TIME 39 9883
TEST_TIME 40 9760
TEST_TIME 41 9796
TEST_TIME 42 9694
TEST_TIME 43 9626
TEST_TIME 44 9815
TEST_TIME 45 9748
TEST_TIME 46 9597
TEST_TIME 47 9549
TEST_TIME 48 9513
TEST_TIME 49 9597
TEST_TIME 50 9519
TEST_TIME 51 9638
TEST_TIME 52 9597
TEST_TIME 53 9560
TEST_TIME 54 9503
TEST_TIME 55 9544
TEST_TIME 56 9575
TEST_TIME 57 9529
TEST_TIME 58 9569
TEST_TIME 59 9629
TEST_TIME 60 9699
TEST_TIME 61 9667
TEST_TIME 62 9696
TEST_TIME 63 9606
TEST_TIME 64 9524
TEST_TIME 65 9449
TEST_TIME 66 9375
TEST_TIME 67 9356
TEST_TIME 68 9402
TEST_TIME 69 9335
TEST_TIME 70 9397
TEST_TIME 71 9348
TEST_TIME 72 9272
TEST_TIME 73 9211
TEST_TIME 74 9142
TEST_TIME 75 9176
TEST_TIME 76 9275
TEST_TIME 77 9220
TEST_TIME 78 9158
TEST_TIME 79 9174
TEST_TIME 80 9177
TEST_TIME 81 9210
TEST_TIME 82 9220
TEST_TIME 83 9243
TEST_TIME 84 9275
TEST_TIME 85 9325
TEST_TIME 86 9355
TEST_TIME 87 9320
TEST_TIME 88 9386
TEST_TIME 89 9370
TEST_TIME 90 9423
TEST_TIME 91 9399
TEST_TIME 92 9332
TEST_TIME 93 9304
TEST_TIME 94 9255
TEST_TIME 95 9274
TEST_TIME 96 9228
TEST_TIME 97 9194
TEST_TIME 98 9188
TEST_TIME 99 9156
TEST_TIME 100 9165
TEST_TIME 101 9152
TEST_TIME 102 9113
TEST_TIME 103 9204
TEST_TIME 104 9171
TEST_TIME 105 9210
TEST_TIME 106 9217
TEST_TIME 107 9261
TEST_TIME 108 9265
TEST_TIME 109 9257
TEST_TIME 110 9291
TEST_TIME 111 9302
TEST_TIME 112 9326
TEST_TIME 113 9271
TEST_TIME 114 9206
TEST_TIME 115 9178
TEST_TIME 116 9189
TEST_TIME 117 9152
TEST_TIME 118 9106
TEST_TIME 119 9086
TEST_TIME 120 9013

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2022 19:41:03 to 24-12-2022 19:41:06.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1494000 1494000 0 0.00 0 40648 7 0 0
doc_list_biud 1493977 1493977 0 0.00 0 59684 28 0 0
sp_add_invoice_to_stock 4878 4780 98 2.00 41 156989 19014 0 98
sp_cancel_adding_invoice 5592 5592 0 0.00 4 89286 765 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 10000 326 0 0
sp_cancel_customer_reserve 552626 552626 0 0.00 0 59686 60 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 2 4800 96 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 3 4937 69 0 0
sp_cancel_supplier_invoice 6221 6215 6 0.09 1 16122 772 0 6
sp_cancel_supplier_order 390 390 0 0.00 9 1116437 107648 0 0
sp_cancel_write_off 9637 9637 0 0.00 1 11371 107 0 0
sp_client_order 19718 19718 0 0.00 3 26211 362 0 0
sp_customer_reserve 445504 445504 0 0.00 0 61434 187 0 0
sp_fill_shopping_cart 30091 30091 0 0.00 1 11881 38 0 0
sp_fill_shopping_cart_clo_res 445503 445503 0 0.00 0 32441 14 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 24346 316 0 0
sp_kill_cost_storno 16220 16220 0 0.00 0 40642 58 0 0
sp_kill_qstorno_ret_qs2qd 1121494 1121494 0 0.00 0 59385 32 0 0
sp_kill_qty_storno 590704 590704 0 0.00 0 59684 70 0 0
sp_lock_selected_doc 20068 20068 0 0.00 0 4928 4 0 0
sp_make_cost_storno 27050 27050 0 0.00 0 22426 73 0 0
sp_multiply_rows_for_pdistr 27041 27041 0 0.00 0 9465 31 0 0
sp_multiply_rows_for_qdistr 19718 19718 0 0.00 0 10943 159 0 0
sp_pay_from_customer 6835 6835 0 0.00 4 20459 138 0 0
sp_pay_to_supplier 5620 5620 0 0.00 4 23230 167 0 0
sp_payment_common 12452 12452 0 0.00 1 23172 46 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 9994 298 0 0
sp_qd_handle_on_invoice_upd_sts 10350 10350 0 0.00 0 41961 341 0 0
sp_qd_handle_on_reserve_upd_sts 9817 9817 0 0.00 0 17538 59 0 0
sp_reserve_write_off 9825 9825 0 0.00 6 17558 193 0 0
sp_supplier_invoice 5268 5268 0 0.00 6 110064 3319 0 0
sp_supplier_order 5108 5108 0 0.00 5 43251 1224 0 0
srv_aggregate_perf_data 93 92 1 1.07 2882 213184 23048 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2037 133167 45771 0 0
srv_find_qd_qs_mism 1035621 1035621 0 0.00 0 12934 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 28 16294 904 0 0
srv_make_money_saldo 705 705 0 0.00 1 2081 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 194077 416900 306385 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4780 4780 0 0.00 0 15622 186 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 3765 80 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 5035 17 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3339 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 920 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 417 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 3778 19 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 25041 1614 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 3624 9 0 0
t$perf-norm:sp_client_order 19718 19718 0 0.00 0 10405 17 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1905 10 0 0
t$perf-norm:sp_pay_from_customer 6835 6835 0 0.00 0 5318 11 0 0
t$perf-norm:sp_pay_to_supplier 5620 5620 0 0.00 0 7859 11 0 0
t$perf-norm:sp_reserve_write_off 9825 9825 0 0.00 0 6229 11 0 0
t$perf-norm:sp_supplier_invoice 5268 5268 0 0.00 0 5131 14 0 0
t$perf-norm:sp_supplier_order 5108 5108 0 0.00 0 6207 15 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 1967 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 650 12 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 1198 19 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 1075 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 1522 383 0 0
v_add_invoice_to_stock 9712 9516 196 2.01 0 4971 18 0 196
v_all_customers 35770 35770 0 0.00 0 25 1 0 0
v_all_suppliers 28966 28966 0 0.00 0 34 1 0 0
v_all_wares 39436 39436 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 365 6 0 0
v_cancel_client_order 3338 3338 0 0.00 0 1629 3 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 598 4 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1939 7 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 263 4 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 505 16 0 12
v_cancel_supplier_order 780 780 0 0.00 0 329 4 0 0
v_cancel_write_off 1928 1928 0 0.00 0 1927 16 0 0
v_max_id_clo_ord 5108 5108 0 0.00 0 840 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 3168 15 0 0
v_max_id_ord_sup 5265 5265 0 0.00 0 2746 3 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 16722 16 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 4966 10 0 0
v_min_id_clo_ord 5108 5108 0 0.00 0 3278 7 0 0
v_min_id_clo_res 995 995 0 0.00 0 8704 23 0 0
v_min_id_ord_sup 5265 5265 0 0.00 0 3525 5 0 0
v_min_non_paid_invoice 5617 5617 0 0.00 0 23158 30 0 0
v_min_non_paid_realizn 6835 6835 0 0.00 0 20395 35 0 0
v_our_firm 9910 9910 0 0.00 0 20 1 0 0
v_reserve_write_off 19632 19632 0 0.00 0 3910 5 0 0
x_lock_depdocs_on_canc_invoice 11380 11380 0 0.00 0 12946 117 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 12322 385 0 0
x_make_qstorno_1000_1200 5108 5108 0 0.00 1 43215 1151 0 0
x_make_qstorno_1200_2000 5268 5268 0 0.00 3 110045 3186 0 0
x_make_qstorno_2100_3300 445504 445504 0 0.00 0 61433 170 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-12-2022 19:41:07 to 24-12-2022 19:41:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:40 31968 8192000000 9921374064 10234937344 999771872 10.876 100 86 172 0 1028288544 6445168 22071504
17:11:15 7254 8192000000 9957576464 10281758720 1045046688 11.313 100 85 171 1 1075447040 8943440 21456912
17:12:33 11446 8192000000 9990405808 10323091456 1069152672 11.544 100 86 172 0 1100995792 10904464 20938656
17:13:48 9547 8192000000 10026568096 10371182592 1112775232 11.959 100 81 162 0 1143524640 11166320 19583088
17:15:00 5614 8192000000 10076732672 10431582208 1157366064 12.379 100 81 160 0 1187786016 11985280 18434672
17:16:09 2240 8192000000 10120956464 10488623104 1212572416 12.893 100 82 160 0 1241905664 11081856 18251392
17:17:14 1863 8192000000 10157710256 10533392384 1248237152 13.222 100 79 158 1 1279285648 13463936 17584560
17:18:17 877 8192000000 10180265216 10559565824 1271020624 13.431 100 83 165 1 1303387456 14567376 17799456
17:19:18 44261 8192000000 10191407168 10596831232 1298902320 13.685 100 50 100 0 1322074512 12287200 10884992
17:21:03 4544 8192000000 10192699312 10603274240 1307844736 13.767 100 25 48 0 1318758272 5854288 5059248
17:22:09 9001 8192000000 10212499744 10625458176 1321838960 13.893 100 22 44 1 1332503200 6237088 4427152
17:23:18 11427 8192000000 10206064624 10626387968 1326624672 13.937 100 18 36 0 1337251216 6734368 3892176
17:24:30 49628 8192000000 10235161232 10640429056 1331097984 13.977 100 48 97 1 1348416976 5335520 11983472
17:26:20 7149 8192000000 10211248928 10628341760 1299840256 13.694 98 25 48 0 1311212528 6311520 5060752
17:27:27 11141 8192000000 10199525632 10614632448 1293210304 13.633 98 18 34 0 1301818112 4752832 3854976
17:28:45 26513 8192000000 10155253584 10566926336 1217804160 12.941 94 18 34 2 1227543360 5421024 4318176
17:30:44 5054 8192000000 10131950144 10522402816 1214756384 12.913 98 35 69 2 1228573072 6090016 7726672
17:31:49 10380 8192000000 10154233744 10552504320 1254361424 13.278 100 22 44 0 1264631616 5598272 4671920
17:33:01 7740 8192000000 10163350000 10573565952 1274159872 13.460 100 18 37 1 1287812192 9677920 3974400
17:34:10 35945 8192000000 10173308384 10585341952 1283484496 13.545 100 19 38 0 1293234352 5431792 4318064
17:35:46 6329 8192000000 10186148480 10601308160 1298350528 13.680 100 24 46 1 1308600624 5179952 5070144
17:36:54 8125 8192000000 10199627952 10618126336 1310911712 13.794 100 24 48 2 1321603248 5560928 5130608
17:38:02 10033 8192000000 10213197504 10631430144 1319009376 13.868 100 15 30 0 1331380640 9329040 3042224
17:39:13 87685 8192000000 10229109088 10638647296 1318922288 13.867 99 43 86 0 1339766000 11491008 9352704
17:41:50 10668 8192000000 10273175856 10684100608 1357842048 14.218 100 59 112 2 1385442480 15127536 12472896
17:43:05 38282 8192000000 10289673104 10713034752 1378751664 14.405 100 44 85 0 1406103840 17720368 9631808
17:44:47 21469 8192000000 10295159936 10714968064 1363559088 14.269 98 40 78 1 1396073488 22633936 9880464
17:46:39 5907 8192000000 10323951568 10743382016 1389519552 14.502 100 50 101 0 1428437440 25727120 13190768
17:47:50 6361 8192000000 10331219232 10749100032 1399447968 14.590 100 58 116 1 1441987536 28905408 13634160
17:49:03 4724 8192000000 10329383200 10751688704 1397003952 14.568 100 63 126 1 1444248416 31598608 15645856
17:50:12 7145 8192000000 10316859360 10736414720 1381647088 14.431 100 48 96 0 1425568256 31932768 11988400
17:51:24 8681 8192000000 10296593296 10701611008 1331083104 13.977 97 72 143 1 1380648176 32708208 16856864
17:52:41 2718 8192000000 10316549328 10717265920 1368099168 14.310 100 72 143 1 1421335552 35554864 17681520
17:53:48 2319 8192000000 10333845408 10737123328 1377411952 14.393 100 76 149 2 1438496000 42047744 19036304
17:54:58 9533 8192000000 10327336464 10742571008 1380545136 14.421 100 44 86 1 1434177648 41647376 11985136
17:56:19 2212 8192000000 10331524016 10738720768 1370478688 14.331 100 68 136 1 1437028128 48917328 17632112
17:57:25 2032 8192000000 10349313216 10752483328 1381751808 14.432 100 86 171 0 1453224656 49926512 21546336
17:58:34 3437 8192000000 10368605952 10776813568 1394077968 14.542 100 73 146 0 1475796544 62612160 19106416
17:59:39 16725 8192000000 10340915264 10762780672 1381211232 14.427 99 34 68 1 1435029024 44194512 9623280
18:00:59 11697 8192000000 10328741088 10760028160 1379761248 14.414 99 32 64 0 1433758816 45091904 8905664
18:02:19 32106 8192000000 10295242352 10723848192 1356952096 14.210 99 25 50 1 1404281280 40300384 7028800
18:04:00 29006 8192000000 10283384464 10720391168 1353340080 14.178 100 19 38 1 1400625104 41687008 5598016
18:05:32 26528 8192000000 10291040624 10739273728 1369435056 14.322 100 18 36 0 1401919568 28310176 4174336
18:07:07 27468 8192000000 10279386304 10728861696 1364355792 14.276 100 13 26 0 1383991392 16607632 3027968
18:08:43 123465 8192000000 10278496912 10735661056 1366073648 14.292 99 21 44 0 1379105696 8702512 4329536
18:12:37 21772 8192000000 10293381760 10741800960 1391516544 14.519 100 24 47 1 1405360336 8493072 5350720
18:14:01 76122 8192000000 10309993296 10760839168 1385733120 14.468 99 14 28 0 1405127328 16039968 3354240
18:16:33 59126 8192000000 10314489456 10770518016 1414413568 14.723 100 20 40 0 1432111168 12967184 4730416
18:18:35 17277 8192000000 10313656448 10762424320 1396973232 14.568 99 29 58 0 1421519136 17665968 6879936
18:19:55 43466 8192000000 10318751456 10764574720 1400625984 14.601 100 28 55 0 1423574208 16786800 6161424
18:21:40 61202 8192000000 10320765600 10789871616 1409624144 14.681 100 25 49 1 1435933232 20406400 5902688
18:23:42 25455 8192000000 10318411984 10792665088 1410192176 14.686 100 4 8 0 1431298880 20201392 905312
18:25:13 94997 8192000000 10339744688 10791432192 1412697696 14.708 100 19 38 0 1430858832 13948512 4212624
18:27:53 87355 8192000000 10322845120 10789314560 1422426176 14.794 100 15 30 0 1444036576 18364512 3245888
18:30:20 26214 8192000000 10328791568 10778484736 1416405680 14.741 100 43 85 0 1434900752 8762928 9732144
18:31:47 16146 8192000000 10317864896 10763603968 1396813552 14.567 100 48 94 2 1418605904 11949344 9843008
18:33:28 66208 8192000000 10288131472 10748837888 1366040544 14.292 98 15 30 0 1378485344 9358816 3085984
18:35:35 39195 8192000000 10306018352 10755936256 1381508368 14.430 99 26 52 0 1398868064 11664912 5694784
18:37:15 133166 8192000000 10297995936 10753970176 1327850736 13.948 95 10 20 0 1350709248 20346816 2511696
18:40:34 116527 8192000000 10251409792 10688786432 1328785728 13.956 97 22 44 2 1342395552 8889056 4720768
18:43:32 31915 8192000000 10269760688 10708606976 1326484688 13.935 97 30 60 0 1344851104 11584624 6781792
18:45:05 93695 8192000000 10274124800 10728636416 1325501360 13.926 97 15 30 1 1346042112 16988960 3551792
18:47:39 80640 8192000000 10263099184 10725199872 1342492128 14.080 98 9 18 0 1358977008 14521872 1963008
18:51:25 118144 8192000000 10266727008 10728669184 1350824160 14.155 99 14 28 0 1372091328 17938256 3328912
18:54:25 63401 8192000000 10250287984 10708889600 1336400672 14.025 98 11 22 0 1346034848 7202064 2432112
18:56:30 38796 8192000000 10263635472 10715656192 1328562160 13.954 97 16 30 1 1342980656 11137136 3281360
18:58:10 27864 8192000000 10273138928 10720141312 1339315648 14.051 98 21 42 0 1359542000 15423168 4803184
18:59:44 48235 8192000000 10265179056 10707853312 1308421376 13.772 96 23 46 0 1324839536 11038704 5379456
19:01:35 30407 8192000000 10277292704 10715451392 1354468288 14.188 99 32 64 0 1374416848 12947648 7000912
19:03:09 87637 8192000000 10266344800 10729930752 1342006720 14.075 98 13 24 1 1358674880 14010352 2657808
19:05:40 37526 8192000000 10293378320 10736553984 1352582048 14.171 98 33 64 0 1371192336 11912448 6697840
19:07:50 47200 8192000000 10303859760 10752552960 1391803104 14.522 100 24 48 0 1408277808 10904496 5570208
19:09:46 36494 8192000000 10314121424 10751152128 1380954928 14.425 99 38 76 0 1399362112 10022256 8384928
19:11:33 79450 8192000000 10299039472 10752512000 1382173584 14.436 99 24 48 0 1396003520 8520112 5309824
19:13:53 43798 8192000000 10270817728 10731794432 1359415856 14.232 98 10 19 0 1371803792 10102720 2285216
19:15:37 68270 8192000000 10287765968 10749972480 1388108176 14.489 100 13 26 0 1406042928 14745872 3188880
19:17:46 69167 8192000000 10288890816 10758524928 1383391696 14.447 99 10 18 1 1397776208 12207920 2176592
19:20:10 31834 8192000000 10290326048 10747330560 1366447744 14.295 98 37 72 0 1389428368 14124048 8856576
19:22:38 18166 8192000000 10285380544 10729754624 1369473232 14.322 99 40 79 0 1386863632 9049664 8340736
19:24:02 57582 8192000000 10281126720 10744475648 1357795056 14.218 98 12 23 0 1368951952 8842320 2314576
19:26:01 59244 8192000000 10282834736 10733170688 1366014768 14.291 99 13 26 0 1379849792 11026400 2808624
19:28:08 96506 8192000000 10292917776 10747363328 1333747696 14.001 96 14 28 0 1356050304 19348656 2953952
19:30:44 126659 8192000000 10266906416 10717937664 1323026192 13.904 96 21 42 0 1335858464 8349504 4482768
19:34:00 7822 8192000000 10264591168 10727596032 1370952672 14.336 100 18 36 0 1389650960 14734880 3963408
19:35:22 53646 8192000000 10246000512 10694410240 1336535616 14.026 98 11 23 0 1344782960 5917328 2330016
19:37:16 64914 8192000000 10267647776 10725564416 1370624672 14.333 100 15 30 0 1386608512 12619744 3364096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2022 19:41:13 to 24-12-2022 19:41:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 98 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2022 19:41:29 to 24-12-2022 19:41:31.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237903
MON$OLDEST_ACTIVE               240473
MON$OLDEST_SNAPSHOT             240473
MON$NEXT_TRANSACTION            240473
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2032736
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Dec 24 19:39:11 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

24.12.22 19:41:33. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-24 19:41:35 No severe PSQL-related problems occured
24.12.22 19:41:36. End of report.