Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.04.2022 16: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 16478664 kB
MemAvailable 31492592 kB
Buffers 0 kB
Cached 15199400 kB
SwapCached 2688 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.10
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-04-13 16:38:15
DTS_END                         2022-04-13 16:38:15.6410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37766
TRN_ID                          237589
ATT_ID                          706
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: 9453.03 22936 1134363
customer order: creation 170.43 398 20452
customer order: refuse 14.84 353 1781
order to supplier: creation 45.52 1383 5462
order to supplier: removal 3.53 106149 423
invoice (draft): creation 45.86 3384 5503
invoice (draft): removal 55.32 768 6638
invoice accept: apply 41.65 18515 4998
invoice accept: cancel 50.13 879 6016
customer reserve: creation 3777.37 186 453284
customer reserve: removal 4954.72 60 594566
realization accept: apply 82.88 185 9945
realization accept: cancel 83.98 112 10077
payment from customer: creation 57.43 127 6891
payment from customer: removal 5.51 85 661
payment to supplier: creation 47.99 150 5759
payment to supplier: removal 3.54 104 425
service: total inventory turnovers 4.78 1005 574
service: total monetary turnovers 6.72 54 806
service: aggregate perf. data 0.82 19926 98
service: refresh index statistics 0.03 304906 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-04-2022 16:39:31 to 13-04-2022 16:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7018
TEST_TIME 2 5662
TEST_TIME 3 5037
TEST_TIME 4 5149
TEST_TIME 5 5595
TEST_TIME 6 5629
TEST_TIME 7 5667
TEST_TIME 8 5850
TEST_TIME 9 6399
TEST_TIME 10 6398
TEST_TIME 11 6336
TEST_TIME 12 6184
TEST_TIME 13 6762
TEST_TIME 14 6697
TEST_TIME 15 6808
TEST_TIME 16 7082
TEST_TIME 17 7320
TEST_TIME 18 7457
TEST_TIME 19 7490
TEST_TIME 20 7858
TEST_TIME 21 8660
TEST_TIME 22 8714
TEST_TIME 23 9162
TEST_TIME 24 9410
TEST_TIME 25 9561
TEST_TIME 26 9623
TEST_TIME 27 10163
TEST_TIME 28 10856
TEST_TIME 29 10633
TEST_TIME 30 10718
TEST_TIME 31 10579
TEST_TIME 32 10454
TEST_TIME 33 10273
TEST_TIME 34 10156
TEST_TIME 35 10179
TEST_TIME 36 10183
TEST_TIME 37 10001
TEST_TIME 38 9929
TEST_TIME 39 9835
TEST_TIME 40 9854
TEST_TIME 41 9868
TEST_TIME 42 9897
TEST_TIME 43 9762
TEST_TIME 44 9655
TEST_TIME 45 9545
TEST_TIME 46 9544
TEST_TIME 47 9697
TEST_TIME 48 9636
TEST_TIME 49 9721
TEST_TIME 50 9662
TEST_TIME 51 9651
TEST_TIME 52 9643
TEST_TIME 53 9715
TEST_TIME 54 9674
TEST_TIME 55 9752
TEST_TIME 56 9848
TEST_TIME 57 9868
TEST_TIME 58 9829
TEST_TIME 59 9828
TEST_TIME 60 9709
TEST_TIME 61 9626
TEST_TIME 62 9558
TEST_TIME 63 9513
TEST_TIME 64 9519
TEST_TIME 65 9516
TEST_TIME 66 9480
TEST_TIME 67 9470
TEST_TIME 68 9431
TEST_TIME 69 9479
TEST_TIME 70 9440
TEST_TIME 71 9605
TEST_TIME 72 9604
TEST_TIME 73 9551
TEST_TIME 74 9609
TEST_TIME 75 9634
TEST_TIME 76 9632
TEST_TIME 77 9711
TEST_TIME 78 9715
TEST_TIME 79 9733
TEST_TIME 80 9685
TEST_TIME 81 9632
TEST_TIME 82 9577
TEST_TIME 83 9514
TEST_TIME 84 9488
TEST_TIME 85 9452
TEST_TIME 86 9419
TEST_TIME 87 9350
TEST_TIME 88 9533
TEST_TIME 89 9486
TEST_TIME 90 9473
TEST_TIME 91 9532
TEST_TIME 92 9500
TEST_TIME 93 9538
TEST_TIME 94 9494
TEST_TIME 95 9646
TEST_TIME 96 9638
TEST_TIME 97 9687
TEST_TIME 98 9604
TEST_TIME 99 9670
TEST_TIME 100 9743
TEST_TIME 101 9793
TEST_TIME 102 9736
TEST_TIME 103 9663
TEST_TIME 104 9653
TEST_TIME 105 9629
TEST_TIME 106 9584
TEST_TIME 107 9629
TEST_TIME 108 9585
TEST_TIME 109 9561
TEST_TIME 110 9519
TEST_TIME 111 9487
TEST_TIME 112 9443
TEST_TIME 113 9519
TEST_TIME 114 9501
TEST_TIME 115 9482
TEST_TIME 116 9552
TEST_TIME 117 9544
TEST_TIME 118 9543
TEST_TIME 119 9531
TEST_TIME 120 9461

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-04-2022 16:39:35 to 13-04-2022 16:39:38.

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 1555360 1555360 0 0.00 0 24095 6 0 0
doc_list_biud 1555360 1555360 0 0.00 0 39067 29 0 0
sp_add_invoice_to_stock 5091 4998 93 1.82 44 183877 18515 0 93
sp_cancel_adding_invoice 6016 6016 0 0.00 5 81341 880 0 0
sp_cancel_client_order 1781 1781 0 0.00 4 10680 353 0 0
sp_cancel_customer_reserve 594566 594566 0 0.00 1 39069 60 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 9093 86 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 6937 105 0 0
sp_cancel_supplier_invoice 6645 6638 7 0.10 2 12879 769 0 7
sp_cancel_supplier_order 423 423 0 0.00 8 1150768 106149 0 0
sp_cancel_write_off 10077 10077 0 0.00 1 16575 112 0 0
sp_client_order 20452 20452 0 0.00 3 25695 399 0 0
sp_customer_reserve 453284 453284 0 0.00 0 102320 187 0 0
sp_fill_shopping_cart 31406 31406 0 0.00 1 9665 38 0 0
sp_fill_shopping_cart_clo_res 453281 453281 0 0.00 0 14330 14 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 30676 304 0 0
sp_kill_cost_storno 17179 17179 0 0.00 0 7747 55 0 0
sp_kill_qstorno_ret_qs2qd 1206270 1206270 0 0.00 0 39064 32 0 0
sp_kill_qty_storno 634436 634436 0 0.00 0 39067 70 0 0
sp_lock_selected_doc 20716 20716 0 0.00 0 8271 7 0 0
sp_make_cost_storno 27585 27585 0 0.00 0 24091 57 0 0
sp_multiply_rows_for_pdistr 27585 27585 0 0.00 0 12053 33 0 0
sp_multiply_rows_for_qdistr 20452 20452 0 0.00 0 12658 186 0 0
sp_pay_from_customer 6891 6891 0 0.00 4 12060 128 0 0
sp_pay_to_supplier 5759 5759 0 0.00 5 17255 150 0 0
sp_payment_common 12650 12650 0 0.00 2 11715 36 0 0
sp_qd_handle_on_cancel_clo 1781 1781 0 0.00 0 10670 321 0 0
sp_qd_handle_on_invoice_upd_sts 11006 11006 0 0.00 0 20215 339 0 0
sp_qd_handle_on_reserve_upd_sts 9945 9945 0 0.00 0 16845 66 0 0
sp_reserve_write_off 9945 9945 0 0.00 6 24149 186 0 0
sp_supplier_invoice 5503 5503 0 0.00 7 83935 3385 0 0
sp_supplier_order 5462 5462 0 0.00 5 32444 1383 0 0
srv_aggregate_perf_data 98 98 0 0.00 2570 162183 19926 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2225 161528 47911 0 0
srv_find_qd_qs_mism 1088037 1088037 0 0.00 0 16075 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 36 35307 1006 0 0
srv_make_money_saldo 806 806 0 0.00 1 22683 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 89674 500449 304907 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4999 4999 0 0.00 0 19572 173 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 5795 129 0 0
t$perf-norm:sp_cancel_client_order 1781 1781 0 0.00 0 4903 14 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 9221 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 281 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 6196 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 954 16 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 32048 1619 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2296 6 0 0
t$perf-norm:sp_client_order 20452 20452 0 0.00 0 20684 19 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2583 12 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 6819 11 0 0
t$perf-norm:sp_pay_to_supplier 5759 5759 0 0.00 0 11374 14 0 0
t$perf-norm:sp_reserve_write_off 9945 9945 0 0.00 0 9000 14 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 9649 19 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 9148 24 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 163 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2693 32 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 2075 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10166 9980 186 1.82 0 4482 18 0 186
v_all_customers 37066 37066 0 0.00 0 18 1 0 0
v_all_suppliers 30320 30320 0 0.00 0 19 1 0 0
v_all_wares 40904 40904 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 456 6 0 0
v_cancel_client_order 3562 3562 0 0.00 0 3105 6 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 768 3 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1009 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3809 9 0 0
v_cancel_supplier_invoice 822 808 14 1.70 0 3753 21 0 14
v_cancel_supplier_order 846 846 0 0.00 0 2854 6 0 0
v_cancel_write_off 1980 1980 0 0.00 0 1793 11 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 681 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 2998 14 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 5829 5 0 0
v_max_non_paid_invoice 1553 1553 0 0.00 0 418 3 0 0
v_max_non_paid_realizn 3705 3705 0 0.00 0 4516 7 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 3370 7 0 0
v_min_id_clo_res 947 947 0 0.00 0 881 6 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 6799 6 0 0
v_min_non_paid_invoice 5759 5759 0 0.00 0 9615 22 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 9787 33 0 0
v_our_firm 10210 10210 0 0.00 0 16 1 0 0
v_reserve_write_off 19890 19890 0 0.00 0 3655 4 0 0
x_lock_depdocs_on_canc_invoice 12250 12250 0 0.00 0 15401 127 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 2739 291 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 2 32431 1294 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 3 83694 3257 0 0
x_make_qstorno_2100_3300 453284 453284 0 0.00 0 102319 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 16:39:39 to 13-04-2022 16:39:43.

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
14:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:20 23903 8192000000 9889918064 10194980864 971284624 10.599 100 89 178 0 1000670608 5591152 23794832
14:10:53 29020 8192000000 9938200016 10255093760 1016697792 11.040 100 88 176 0 1047843168 7840112 23305264
14:12:28 17007 8192000000 9972067440 10306555904 1055240288 11.411 100 78 155 1 1084339888 9148592 19951008
14:13:50 13012 8192000000 10041062368 10388869120 1123924144 12.064 100 73 146 1 1151945120 11390576 16630400
14:15:09 7650 8192000000 10093252400 10452336640 1179421264 12.585 100 76 152 1 1209604720 13244064 16939392
14:16:20 5075 8192000000 10135120448 10502361088 1218583024 12.949 100 83 166 1 1251625488 14848368 18194096
14:17:28 2225 8192000000 10168642528 10541613056 1251491456 13.252 100 88 177 1 1285000368 13966032 19542880
14:18:33 2234 8192000000 10202287552 10581299200 1285622768 13.564 100 95 190 0 1319584160 12644544 21316848
14:19:37 2581 8192000000 10198893456 10588655616 1293679456 13.638 100 66 131 2 1318807792 10751312 14377024
14:20:41 9433 8192000000 10189781008 10598817792 1301720128 13.711 100 29 55 2 1313498432 5659248 6119056
14:21:56 35027 8192000000 10235255456 10627903488 1313317888 13.816 100 76 151 4 1336772400 6934800 16519712
14:23:32 7470 8192000000 10213899664 10628800512 1322052496 13.895 100 16 32 2 1331259520 5424624 3782400
14:24:43 9414 8192000000 10220043184 10644148224 1329239632 13.960 100 21 42 1 1344134480 10142256 4752592
14:25:52 61725 8192000000 10236334592 10652803072 1328718528 13.956 99 41 83 2 1343651824 5285712 9647584
14:27:55 5411 8192000000 10218694288 10637762560 1319535824 13.873 99 32 66 0 1334096000 6754176 7806000
14:29:03 7414 8192000000 10179016240 10587062272 1270384096 13.425 97 29 57 3 1283329088 5991984 6953008
14:30:11 11163 8192000000 10140978880 10541953024 1212511856 12.892 96 12 24 0 1220657088 5724512 2420720
14:31:24 49192 8192000000 10159523312 10556157952 1175889424 12.552 92 20 40 0 1185736096 5483728 4362944
14:33:16 8613 8192000000 10168222736 10571718656 1269437072 13.416 100 35 70 3 1284373760 6764544 8172144
14:34:27 11149 8192000000 10165753200 10576437248 1280095504 13.514 100 22 44 2 1291194560 5799184 5299872
14:35:40 59535 8192000000 10184957328 10601078784 1292995248 13.632 100 29 56 1 1305010848 5149152 6866448
14:37:42 8425 8192000000 10189559456 10604195840 1301238112 13.706 100 26 50 2 1311935040 5384384 5312544
14:38:55 7946 8192000000 10237702752 10643632128 1326397488 13.935 100 41 80 4 1341834880 6763120 8674272
14:40:11 37055 8192000000 10253739600 10665631744 1340603440 14.063 100 42 84 1 1359692976 9378160 9711376
14:41:53 46746 8192000000 10262934992 10681737216 1354038512 14.184 100 36 72 1 1374680272 12293360 8348400
14:43:51 17662 8192000000 10273926032 10691964928 1360565184 14.242 100 50 101 1 1387201744 14222576 12413984
14:45:12 4149 8192000000 10296799088 10708353024 1372302432 14.348 100 68 136 1 1403303808 15101664 15899712
14:46:21 7607 8192000000 10310789824 10726391808 1382491072 14.439 100 66 131 3 1420435072 22936336 15007664
14:47:32 5988 8192000000 10320503984 10740436992 1393844864 14.540 100 79 156 2 1435715904 22926704 18944336
14:48:47 8178 8192000000 10326868080 10739318784 1392259664 14.526 100 74 148 0 1433304320 23618656 17426000
14:50:24 4050 8192000000 10333181296 10746023936 1393316144 14.535 100 69 137 2 1432098944 22204464 16578336
14:51:34 8572 8192000000 10334724656 10751209472 1399289872 14.589 100 66 132 0 1442325488 26729888 16305728
14:52:47 13609 8192000000 10329372896 10749599744 1395057648 14.551 99 51 100 0 1435886496 27511008 13317840
14:54:05 2222 8192000000 10335847536 10738221056 1368676896 14.315 98 86 172 0 1423526560 33321584 21528080
14:55:14 2692 8192000000 10347439264 10755362816 1392602128 14.529 100 82 164 0 1450968896 37526384 20840384
14:56:23 6326 8192000000 10356039024 10765148160 1397800608 14.575 100 79 157 1 1455852736 38528000 19524128
14:57:34 3438 8192000000 10365344528 10772348928 1396771408 14.566 100 87 170 1 1465682576 47246736 21664432
14:58:41 10001 8192000000 10344380704 10763563008 1381227808 14.428 100 51 102 1 1448685216 53180048 14277360
15:00:01 14133 8192000000 10336962624 10763583488 1375673392 14.378 99 44 89 4 1433074304 45619728 11781184
15:01:21 11512 8192000000 10333069312 10762231808 1372754208 14.352 99 43 86 0 1434158816 49631712 11772896
15:02:43 22164 8192000000 10315082336 10748002304 1378673584 14.405 100 28 56 0 1424425280 38291600 7460096
15:04:12 24108 8192000000 10298142496 10743971840 1361878912 14.254 100 24 48 0 1412140752 43329072 6932768
15:05:49 45822 8192000000 10278242592 10722430976 1314518992 13.827 98 18 36 0 1355087344 35487632 5080720
15:07:40 64671 8192000000 10267171408 10694373376 1306981680 13.759 96 40 78 1 1326796080 10425232 9389168
15:09:50 32562 8192000000 10270323968 10700148736 1338422400 14.043 99 17 34 2 1362824592 20001616 4400576
15:11:29 80566 8192000000 10265410128 10707730432 1348311360 14.132 99 32 64 1 1366592864 10803568 7477936
15:14:19 105660 8192000000 10306611552 10737414144 1350238144 14.150 98 35 70 2 1367723456 9309680 8175632
15:17:05 24602 8192000000 10304178096 10746048512 1362751440 14.262 98 30 60 2 1388494544 18872352 6870752
15:18:31 36020 8192000000 10288967008 10748305408 1383693600 14.450 100 19 38 2 1404635616 16166160 4775856
15:20:08 33888 8192000000 10263560048 10727174144 1370342480 14.330 100 11 23 0 1386980272 13894496 2743296
15:21:45 161528 8192000000 10305823792 10771382272 1391586928 14.520 100 21 42 0 1406403984 9772736 5044320
15:25:40 70855 8192000000 10308953712 10753249280 1375145504 14.373 99 14 28 1 1394957024 16570704 3240816
15:27:53 54238 8192000000 10294762608 10745954304 1356201120 14.203 97 33 65 1 1372772192 8734912 7836160
15:29:51 44972 8192000000 10298432192 10759196672 1368719984 14.316 98 12 24 0 1392008032 20475120 2812928
15:31:41 107178 8192000000 10313909968 10770673664 1387734608 14.486 99 17 34 0 1409382144 17733200 3914336
15:34:29 66060 8192000000 10312738352 10768924672 1390886224 14.514 99 17 34 0 1406945056 12654128 3404704
15:36:51 51185 8192000000 10273926560 10717184000 1272011696 13.440 92 33 67 1 1293131184 13681264 7438224
15:38:42 46257 8192000000 10275337136 10722267136 1372642976 14.351 100 34 64 0 1385469152 6165504 6660672
15:40:30 45425 8192000000 10279083600 10733080576 1374196352 14.365 99 13 26 0 1386230288 9316688 2717248
15:42:35 65510 8192000000 10261048496 10723831808 1362254384 14.258 99 23 46 0 1378740240 10900000 5585856
15:44:44 58083 8192000000 10265713424 10720509952 1347764560 14.127 98 10 20 0 1360350544 10363472 2222512
15:46:42 29590 8192000000 10240623344 10697859072 1305665840 13.747 95 14 28 0 1321693616 12869168 3158608
15:48:17 31863 8192000000 10297854256 10736840704 1372096432 14.346 100 42 82 1 1398421168 15441664 10883072
15:49:54 58650 8192000000 10275361696 10727686144 1342796144 14.083 98 10 19 1 1361545328 16745936 2003248
15:51:57 50422 8192000000 10284740080 10732412928 1366167472 14.293 99 20 40 1 1383274432 12502880 4604080
15:53:53 56206 8192000000 10290183344 10722291712 1343752560 14.091 98 28 56 0 1362756144 12473776 6529808
15:55:50 74628 8192000000 10271948480 10735648768 1340276064 14.060 98 9 18 0 1368312000 25835776 2200160
15:58:07 72673 8192000000 10286593536 10728198144 1372818096 14.352 100 29 58 0 1389833056 10192128 6822832
16:00:25 57040 8192000000 10266226000 10717122560 1336119440 14.022 97 18 36 1 1350603600 10185712 4298448
16:02:23 79078 8192000000 10262695712 10714710016 1321231360 13.888 96 12 24 0 1343933232 19379520 3322352
16:05:17 94047 8192000000 10248587712 10729656320 1332622000 13.991 99 11 22 0 1367149904 31461824 3066080
16:07:52 33354 8192000000 10272882448 10717372416 1353417168 14.178 99 23 46 1 1369416928 9893936 6105824
16:09:26 53916 8192000000 10286613360 10750328832 1380084720 14.417 100 16 32 0 1397389024 13690720 3613584
16:11:21 71000 8192000000 10316426576 10768179200 1396107520 14.560 100 19 38 1 1418193632 17515552 4570560
16:13:32 72662 8192000000 10296951040 10752876544 1381594528 14.431 99 19 36 0 1404872592 19231328 4046736
16:15:46 69664 8192000000 10328961264 10772275200 1401768912 14.611 100 17 35 0 1424682272 19245664 3667696
16:17:56 64089 8192000000 10314420368 10782982144 1373655472 14.360 98 13 26 0 1393221264 16594592 2971200
16:20:01 138586 8192000000 10296374592 10755325952 1359702208 14.235 97 27 54 0 1374912768 9597040 5613520
16:23:20 57775 8192000000 10279228016 10736611328 1371332512 14.339 99 25 50 1 1384918336 7151792 6434032
16:25:18 50457 8192000000 10284380224 10752278528 1363035680 14.265 98 15 30 0 1376331728 9302288 3993760
16:27:34 84251 8192000000 10298296368 10751320064 1375509952 14.376 99 19 38 0 1399816544 19551312 4755280
16:30:15 65045 8192000000 10277551744 10729885696 1338246448 14.042 97 24 47 0 1361297488 17270848 5780192
16:32:21 70648 8192000000 10292725696 10746384384 1371967312 14.345 99 18 36 0 1391661392 14690992 5003088
16:34:40 72644 8192000000 10271112160 10720927744 1305813216 13.748 95 17 34 0 1322305280 12179344 4312720
16:36:53 56825 8192000000 10302051696 10741710848 1371387440 14.339 100 18 36 0 1387016896 11358432 4271024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-04-2022 16:39:45 to 13-04-2022 16:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          235718
MON$OLDEST_ACTIVE               238832
MON$OLDEST_SNAPSHOT             238832
MON$NEXT_TRANSACTION            238832
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                       2044512
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:310: 
:311: mscs-test-161.budzdorov.ru    Wed Apr 13 16:38:29 2022
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:313: 
:314: 
:315: mscs-test-161.budzdorov.ru    Wed Apr 13 16:38:32 2022
:316:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:317: 
:318: 
:319: mscs-test-161.budzdorov.ru    Wed Apr 13 16:38:32 2022
:320:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:321: 
:322: 
:323: mscs-test-161.budzdorov.ru    Wed Apr 13 16:38:32 2022
:324:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:325: 
--- end of diff output ---

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

13.04.22 16:40:04. 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-04-13 16:40:06 No severe PSQL-related problems occured
13.04.22 16:40:07. End of report.