Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2022 19:39
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 16515604 kB
MemAvailable 31365084 kB
Buffers 0 kB
Cached 15185272 kB
SwapCached 1232 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.9
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-01-01 19:38:03
DTS_END                         2022-01-01 19:38:03.7350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49672
TRN_ID                          239255
ATT_ID                          632
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: 9408.27 22967 1128992
customer order: creation 170.21 367 20425
customer order: refuse 14.81 335 1777
order to supplier: creation 44.53 1152 5343
order to supplier: removal 3.42 115935 410
invoice (draft): creation 45.38 3179 5445
invoice (draft): removal 54.48 799 6537
invoice accept: apply 41.88 16894 5026
invoice accept: cancel 49.46 777 5935
customer reserve: creation 3862.06 166 463447
customer reserve: removal 4830.99 65 579719
realization accept: apply 81.38 186 9765
realization accept: cancel 80.17 116 9620
payment from customer: creation 59.98 104 7198
payment from customer: removal 5.68 79 681
payment to supplier: creation 48.34 133 5801
payment to supplier: removal 3.46 78 415
service: total inventory turnovers 4.59 1107 551
service: total monetary turnovers 6.58 28 789
service: aggregate perf. data 0.87 18261 104
service: refresh index statistics 0.03 299579 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2022 19:39:30 to 01-01-2022 19:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4811
TEST_TIME 2 3554
TEST_TIME 3 3554
TEST_TIME 4 3878
TEST_TIME 5 4017
TEST_TIME 6 4023
TEST_TIME 7 3974
TEST_TIME 8 4576
TEST_TIME 9 4640
TEST_TIME 10 4641
TEST_TIME 11 4834
TEST_TIME 12 5094
TEST_TIME 13 5574
TEST_TIME 14 5771
TEST_TIME 15 6269
TEST_TIME 16 7273
TEST_TIME 17 7325
TEST_TIME 18 7842
TEST_TIME 19 8121
TEST_TIME 20 8588
TEST_TIME 21 8922
TEST_TIME 22 9117
TEST_TIME 23 9055
TEST_TIME 24 9580
TEST_TIME 25 9845
TEST_TIME 26 10690
TEST_TIME 27 11518
TEST_TIME 28 11382
TEST_TIME 29 11079
TEST_TIME 30 11124
TEST_TIME 31 10951
TEST_TIME 32 10871
TEST_TIME 33 10741
TEST_TIME 34 11062
TEST_TIME 35 10806
TEST_TIME 36 10667
TEST_TIME 37 10521
TEST_TIME 38 10427
TEST_TIME 39 10650
TEST_TIME 40 10734
TEST_TIME 41 10597
TEST_TIME 42 10464
TEST_TIME 43 10402
TEST_TIME 44 10224
TEST_TIME 45 10119
TEST_TIME 46 10080
TEST_TIME 47 10022
TEST_TIME 48 9974
TEST_TIME 49 9956
TEST_TIME 50 9831
TEST_TIME 51 9895
TEST_TIME 52 9865
TEST_TIME 53 9822
TEST_TIME 54 9913
TEST_TIME 55 9960
TEST_TIME 56 9944
TEST_TIME 57 9945
TEST_TIME 58 9952
TEST_TIME 59 9984
TEST_TIME 60 9948
TEST_TIME 61 10003
TEST_TIME 62 10015
TEST_TIME 63 10031
TEST_TIME 64 10070
TEST_TIME 65 10083
TEST_TIME 66 10107
TEST_TIME 67 10118
TEST_TIME 68 10205
TEST_TIME 69 10070
TEST_TIME 70 9985
TEST_TIME 71 9886
TEST_TIME 72 9884
TEST_TIME 73 9852
TEST_TIME 74 9777
TEST_TIME 75 9778
TEST_TIME 76 9748
TEST_TIME 77 9646
TEST_TIME 78 9564
TEST_TIME 79 9709
TEST_TIME 80 9706
TEST_TIME 81 9653
TEST_TIME 82 9680
TEST_TIME 83 9750
TEST_TIME 84 9699
TEST_TIME 85 9688
TEST_TIME 86 9800
TEST_TIME 87 9885
TEST_TIME 88 9864
TEST_TIME 89 9855
TEST_TIME 90 9857
TEST_TIME 91 9849
TEST_TIME 92 9809
TEST_TIME 93 9762
TEST_TIME 94 9718
TEST_TIME 95 9670
TEST_TIME 96 9629
TEST_TIME 97 9631
TEST_TIME 98 9636
TEST_TIME 99 9665
TEST_TIME 100 9636
TEST_TIME 101 9635
TEST_TIME 102 9611
TEST_TIME 103 9666
TEST_TIME 104 9684
TEST_TIME 105 9660
TEST_TIME 106 9695
TEST_TIME 107 9751
TEST_TIME 108 9732
TEST_TIME 109 9764
TEST_TIME 110 9712
TEST_TIME 111 9651
TEST_TIME 112 9604
TEST_TIME 113 9588
TEST_TIME 114 9579
TEST_TIME 115 9528
TEST_TIME 116 9501
TEST_TIME 117 9508
TEST_TIME 118 9453
TEST_TIME 119 9490
TEST_TIME 120 9413

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2022 19:39:34 to 01-01-2022 19:39:37.

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 1558746 1558746 0 0.00 0 30478 6 0 0
doc_list_biud 1558720 1558720 0 0.00 0 38680 30 0 0
sp_add_invoice_to_stock 5109 5026 83 1.62 23 242392 16895 0 83
sp_cancel_adding_invoice 5935 5935 0 0.00 3 68893 778 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 13811 336 0 0
sp_cancel_customer_reserve 579719 579719 0 0.00 1 38688 66 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 5081 80 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 4013 78 0 0
sp_cancel_supplier_invoice 6542 6537 5 0.07 1 32732 800 0 5
sp_cancel_supplier_order 410 410 0 0.00 6 1054042 115936 0 0
sp_cancel_write_off 9620 9620 0 0.00 1 31156 117 0 0
sp_client_order 20425 20425 0 0.00 3 31622 367 0 0
sp_customer_reserve 463447 463447 0 0.00 0 129681 166 0 0
sp_fill_shopping_cart 31213 31213 0 0.00 1 13360 35 0 0
sp_fill_shopping_cart_clo_res 463446 463446 0 0.00 0 19370 16 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 24284 405 0 0
sp_kill_cost_storno 16651 16651 0 0.00 0 12202 60 0 0
sp_kill_qstorno_ret_qs2qd 1176005 1176005 0 0.00 0 35245 35 0 0
sp_kill_qty_storno 618770 618770 0 0.00 0 38679 75 0 0
sp_lock_selected_doc 20551 20551 0 0.00 0 6860 4 0 0
sp_make_cost_storno 27784 27784 0 0.00 0 22921 56 0 0
sp_multiply_rows_for_pdistr 27770 27770 0 0.00 0 7010 27 0 0
sp_multiply_rows_for_qdistr 20425 20425 0 0.00 0 31611 186 0 0
sp_pay_from_customer 7198 7198 0 0.00 4 18892 105 0 0
sp_pay_to_supplier 5801 5801 0 0.00 4 16829 133 0 0
sp_payment_common 12987 12987 0 0.00 1 18829 32 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 13805 306 0 0
sp_qd_handle_on_invoice_upd_sts 10948 10948 0 0.00 0 18069 333 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 34551 69 0 0
sp_reserve_write_off 9765 9765 0 0.00 6 34898 186 0 0
sp_supplier_invoice 5445 5445 0 0.00 6 96578 3180 0 0
sp_supplier_order 5343 5343 0 0.00 6 39478 1153 0 0
srv_aggregate_perf_data 104 104 0 0.00 2626 286910 18262 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 941 129873 51728 0 0
srv_find_qd_qs_mism 1081704 1081704 0 0.00 0 30477 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 36 85565 1107 0 0
srv_make_money_saldo 789 789 0 0.00 1 3013 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 108975 406168 299579 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5026 5026 0 0.00 0 26817 164 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 3925 101 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 6072 15 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 7388 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 3346 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1470 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 981 15 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 24093 1678 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 570 3 0 0
t$perf-norm:sp_client_order 20425 20425 0 0.00 0 9154 19 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 2494 7 0 0
t$perf-norm:sp_pay_from_customer 7198 7198 0 0.00 0 9276 15 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 29820 17 0 0
t$perf-norm:sp_reserve_write_off 9765 9765 0 0.00 0 6127 10 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 8189 23 0 0
t$perf-norm:sp_supplier_order 5343 5343 0 0.00 0 8510 19 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 718 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2996 58 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 1717 19 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 859 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10192 10026 166 1.62 0 5239 19 0 166
v_all_customers 37630 37630 0 0.00 0 18 1 0 0
v_all_suppliers 30004 30004 0 0.00 0 22 1 0 0
v_all_wares 40850 40850 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1809 13 0 0
v_cancel_client_order 3554 3554 0 0.00 0 4034 6 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 3820 9 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2760 7 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1099 6 0 0
v_cancel_supplier_invoice 778 768 10 1.28 0 4582 21 0 10
v_cancel_supplier_order 820 820 0 0.00 0 1232 5 0 0
v_cancel_write_off 1978 1978 0 0.00 0 3096 19 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 898 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 2049 8 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 5147 7 0 0
v_max_non_paid_invoice 1580 1580 0 0.00 0 583 3 0 0
v_max_non_paid_realizn 3688 3688 0 0.00 0 18825 10 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 4165 7 0 0
v_min_id_clo_res 987 987 0 0.00 0 1312 6 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 3852 5 0 0
v_min_non_paid_invoice 5794 5794 0 0.00 0 6902 27 0 0
v_min_non_paid_realizn 7192 7192 0 0.00 0 8369 23 0 0
v_our_firm 10230 10230 0 0.00 0 13 1 0 0
v_reserve_write_off 19518 19518 0 0.00 0 4955 7 0 0
x_lock_depdocs_on_canc_invoice 12088 12088 0 0.00 0 11084 117 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 6119 335 0 0
x_make_qstorno_1000_1200 5343 5343 0 0.00 1 39446 1083 0 0
x_make_qstorno_1200_2000 5445 5445 0 0.00 3 96569 3054 0 0
x_make_qstorno_2100_3300 463447 463447 0 0.00 0 129680 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2022 19:39:38 to 01-01-2022 19:39:42.

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:07 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:08 825 8192000000 9870265968 10174287872 961498752 10.504 100 89 178 0 990869328 5361152 24009424
17:10:11 22612 8192000000 9927254160 10246037504 1005100544 10.928 100 87 174 2 1036070608 8172416 22797648
17:11:39 17003 8192000000 9962586912 10289180672 1038214832 11.248 100 85 170 0 1070489904 9976416 22298656
17:12:57 11898 8192000000 10009641760 10350514176 1091711584 11.759 100 80 160 0 1122447280 11339328 19396368
17:14:10 10085 8192000000 10054409728 10409238528 1140727344 12.222 100 78 156 0 1171787504 12565120 18495040
17:15:23 8243 8192000000 10099255968 10469322752 1195865312 12.738 100 72 142 3 1223929200 11621344 16442544
17:16:33 3238 8192000000 10143551680 10519003136 1238425760 13.132 100 85 168 3 1269898032 13205952 18266320
17:17:39 2219 8192000000 10184188832 10562433024 1265119200 13.377 100 93 186 5 1302257552 16469888 20668464
17:18:43 1289 8192000000 10215880592 10597941248 1293931424 13.640 100 95 190 1 1329613024 13972128 21709472
17:19:45 15412 8192000000 10217286576 10612031488 1309285344 13.780 100 54 110 1 1329510448 6663520 13561584
17:21:00 4195 8192000000 10211699600 10624368640 1319758128 13.875 100 37 74 0 1335203280 7108272 8336880
17:22:07 7440 8192000000 10212702544 10630029312 1327595520 13.945 100 31 61 1 1340448704 5775760 7077424
17:23:19 9133 8192000000 10221860880 10642362368 1334463024 14.007 100 19 37 1 1344524480 6029280 4032176
17:24:29 35129 8192000000 10263854272 10664910848 1338976960 14.048 100 60 120 3 1358619216 5033984 14608272
17:26:05 11108 8192000000 10231634416 10658922496 1331711984 13.983 99 25 50 2 1343518640 6384400 5422256
17:27:17 8155 8192000000 10231463120 10658062336 1343183664 14.086 100 14 27 0 1352290576 6061312 3045600
17:28:25 20119 8192000000 10166391760 10578722816 1245877984 13.200 94 31 61 0 1257864224 5303296 6682944
17:29:46 8773 8192000000 10168734144 10578042880 1227401024 13.030 95 9 17 1 1234351248 4573520 2376704
17:30:55 7194 8192000000 10148990304 10550583296 1234563904 13.096 98 24 47 2 1246395392 6396288 5435200
17:32:03 10982 8192000000 10153104512 10558779392 1232749984 13.079 97 28 56 1 1245112864 5513216 6849664
17:33:16 67649 8192000000 10162750160 10569912320 1264995328 13.376 99 26 50 0 1277015296 5609360 6410608
17:35:27 10016 8192000000 10181844896 10595942400 1290313696 13.607 100 16 32 0 1303373264 9463760 3595808
17:36:38 17091 8192000000 10197707664 10609598464 1303751296 13.729 100 17 34 2 1313372864 5541136 4080432
17:37:59 17869 8192000000 10236848480 10643374080 1322250848 13.897 100 42 84 1 1342173424 10312384 9610192
17:39:23 117778 8192000000 10245554576 10670432256 1349574576 14.144 100 31 62 1 1367800928 11429616 6796736
17:42:24 22639 8192000000 10292413712 10706735104 1374541840 14.368 100 57 114 0 1402813424 15511056 12760528
17:43:59 22912 8192000000 10309537120 10729783296 1392461440 14.528 100 42 83 0 1421159776 18969664 9728672
17:45:24 35090 8192000000 10328447824 10749861888 1405821888 14.647 100 47 94 2 1440561488 23039056 11700544
17:47:13 10870 8192000000 10341899536 10765729792 1417233136 14.748 100 51 102 1 1456079344 25743056 13103152
17:48:33 5219 8192000000 10373591936 10791874560 1424777312 14.815 99 62 124 0 1471847376 30780176 16289888
17:49:45 4016 8192000000 10387023792 10796961792 1434474832 14.901 100 87 171 0 1490796256 36436368 19885056
17:50:54 4360 8192000000 10379680736 10790834176 1421028240 14.782 100 78 154 1 1482841440 42719056 19094144
17:52:09 3356 8192000000 10382531328 10798977024 1425831600 14.824 100 65 130 0 1490026320 46792864 17401856
17:53:17 3100 8192000000 10389112112 10794889216 1411655952 14.699 100 84 168 0 1488241664 54917872 21667840
17:54:24 938 8192000000 10381104240 10786512896 1396054864 14.560 99 96 189 3 1479279616 59181104 24043648
17:55:30 5878 8192000000 10355976752 10771046400 1390516304 14.510 100 71 143 2 1462088304 52264096 19307904
17:56:42 3044 8192000000 10373624560 10779344896 1388444720 14.492 100 88 173 6 1470713184 60245744 22022720
17:57:51 1583 8192000000 10385225440 10798698496 1398900480 14.585 99 79 155 4 1474658128 55502448 20255200
17:58:54 18919 8192000000 10362421376 10789203968 1397737904 14.575 100 42 84 2 1466006832 56253072 12015856
18:00:16 11417 8192000000 10372198016 10799120384 1408543504 14.671 100 55 110 0 1478851072 55413488 14894080
18:01:36 20872 8192000000 10337707584 10773307392 1339551248 14.053 96 26 52 1 1404033088 56655296 7826544
18:02:59 31442 8192000000 10331725312 10774237184 1373317616 14.357 100 30 60 0 1433791984 52167840 8306528
18:04:47 38222 8192000000 10288746016 10748272640 1349919248 14.147 98 19 38 0 1404530928 49246992 5364688
18:06:26 90446 8192000000 10271147248 10713120768 1328823200 13.957 97 24 48 1 1346378128 11302672 6252256
18:09:19 61762 8192000000 10277093328 10713489408 1313010656 13.813 95 23 46 0 1328375360 10403168 4961536
18:11:27 84524 8192000000 10290006144 10758008832 1366069696 14.292 99 19 38 0 1389556192 19083024 4403472
18:14:02 124462 8192000000 10312840144 10750492672 1333924784 14.003 95 22 44 3 1358364256 19305776 5133696
18:17:08 48232 8192000000 10306156720 10776465408 1387605824 14.484 100 33 67 0 1417052112 21578128 7868160
18:18:57 58482 8192000000 10284209840 10742624256 1380752608 14.423 99 11 22 1 1392383664 9247168 2383888
18:20:56 73032 8192000000 10282873120 10744074240 1386181168 14.472 100 19 38 1 1400084288 9616128 4286992
18:23:09 97949 8192000000 10292206416 10747412480 1389832688 14.504 100 28 54 2 1406412016 10018720 6560608
18:25:52 101019 8192000000 10300719936 10758672384 1377913360 14.398 98 14 28 0 1391875184 10573504 3388320
18:28:33 68260 8192000000 10299566032 10772873216 1392713344 14.530 99 21 42 1 1416107088 17255472 6138272
18:30:42 85032 8192000000 10318227664 10773491712 1406698640 14.655 100 19 38 1 1429113168 17820160 4594368
18:33:21 50131 8192000000 10310819840 10776219648 1399920512 14.594 100 11 22 1 1414958576 12631360 2406704
18:35:12 24757 8192000000 10292588976 10742452224 1359173024 14.230 97 27 50 2 1373681120 7941776 6566320
18:36:57 21629 8192000000 10295242768 10742841344 1367867520 14.308 98 33 66 3 1387267184 11084544 8315120
18:38:24 8549 8192000000 10285393680 10724204544 1363938016 14.273 99 46 92 0 1382342448 8516304 9888128
18:39:35 44811 8192000000 10268398880 10717122560 1334034624 14.004 97 21 42 0 1350718736 12002384 4681728
18:41:21 22302 8192000000 10267822480 10712936448 1350590256 14.153 99 27 54 1 1366071520 9489456 5991808
18:42:47 79802 8192000000 10285029552 10731372544 1340344176 14.061 97 21 42 1 1359207840 13800848 5062816
18:45:09 102273 8192000000 10300913792 10768658432 1341136304 14.068 97 9 18 0 1356260848 12808192 2316352
18:47:52 61520 8192000000 10248238768 10689073152 1314849168 13.830 95 19 36 0 1325839472 5942192 5048112
18:49:55 34878 8192000000 10268997280 10718920704 1371891392 14.344 100 30 60 1 1387717776 9099104 6727280
18:51:40 43746 8192000000 10268005392 10721988608 1348594272 14.135 98 10 20 0 1359435008 8546208 2294528
18:53:35 45915 8192000000 10261711872 10710962176 1343935488 14.093 98 15 29 1 1361676384 14256640 3484256
18:55:21 89807 8192000000 10271870720 10724716544 1354598480 14.189 99 16 32 0 1370425744 11933216 3894048
18:57:51 49639 8192000000 10259424576 10706710528 1307700816 13.765 96 15 30 0 1318817872 7992416 3124640
18:59:41 39272 8192000000 10282861328 10725449728 1353662480 14.180 99 30 60 1 1373725264 12708288 7354496
19:01:28 77787 8192000000 10279884560 10726440960 1317311760 13.852 96 15 30 0 1336323904 15517232 3494912
19:04:02 54152 8192000000 10276911472 10725015552 1340329392 14.060 98 10 20 0 1364120416 21545760 2245264
19:06:04 95449 8192000000 10281111456 10734845952 1346228144 14.114 98 12 24 0 1358807712 10093728 2485840
19:08:40 36648 8192000000 10310818080 10752466944 1378053888 14.399 99 28 56 1 1400656464 16498496 6104080
19:10:19 81091 8192000000 10283847424 10748133376 1389185728 14.499 100 13 26 0 1404643584 12889728 2568128
19:12:40 74119 8192000000 10291786528 10754469888 1370300192 14.330 98 12 24 0 1379405072 6752544 2352336
19:15:07 56981 8192000000 10295732800 10751315968 1374425856 14.367 98 12 24 0 1386209680 8991184 2792640
19:17:12 68877 8192000000 10284505248 10738544640 1322170464 13.896 95 16 32 0 1338671968 13364704 3136800
19:19:29 53712 8192000000 10286683904 10734075904 1363363856 14.268 99 22 42 1 1378149360 9775696 5009808
19:21:24 82649 8192000000 10260346768 10710409216 1303023552 13.723 95 12 24 1 1321757504 16015712 2718240
19:23:53 106283 8192000000 10253551552 10719784960 1296669952 13.665 95 11 22 0 1321087488 21621856 2795680
19:26:40 129873 8192000000 10255290688 10704269312 1296801840 13.666 95 24 48 2 1314737968 12496240 5439888
19:30:06 74458 8192000000 10263842752 10717380608 1354354288 14.187 99 19 38 0 1366933552 8032992 4546272
19:32:23 75533 8192000000 10255594976 10713247744 1342121984 14.077 98 10 20 0 1353391776 9237344 2032448
19:34:39 74391 8192000000 10277212224 10733338624 1362915856 14.264 99 12 24 1 1382498880 16512976 3070048
19:36:54 73355 8192000000 9452704160 9779826688 601928544 6.844 44 9 20 1 611012784 7068736 2015504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2022 19:39:44 to 01-01-2022 19:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2022 19:40:00 to 01-01-2022 19:40:02.

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          239544
MON$OLDEST_ACTIVE               239545
MON$OLDEST_SNAPSHOT             239545
MON$NEXT_TRANSACTION            239545
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                       2033760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:267: 
:268: mscs-test-161.budzdorov.ru    Sat Jan  1 19:38:11 2022
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
--- end of diff output ---

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

01.01.22 19:40:03. 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-01-01 19:40:05 No severe PSQL-related problems occured
01.01.22 19:40:07. End of report.