Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.2022 01: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 16062532 kB
MemAvailable 31320528 kB
Buffers 0 kB
Cached 15675840 kB
SwapCached 2444 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-06-13 01:38:21
DTS_END                         2022-06-13 01:38:21.8370
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/55050
TRN_ID                          243183
ATT_ID                          629
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: 9461.49 19460 1135379
customer order: creation 175.33 329 21039
customer order: refuse 14.77 368 1772
order to supplier: creation 47.17 1274 5660
order to supplier: removal 3.48 92346 417
invoice (draft): creation 45.78 3513 5493
invoice (draft): removal 53.98 779 6478
invoice accept: apply 42.53 18060 5103
invoice accept: cancel 48.89 782 5867
customer reserve: creation 3939.70 178 472764
customer reserve: removal 4782.60 51 573912
realization accept: apply 85.70 195 10284
realization accept: cancel 88.81 97 10657
payment from customer: creation 60.88 130 7306
payment from customer: removal 5.86 77 703
payment to supplier: creation 50.32 152 6038
payment to supplier: removal 3.36 163 403
service: total inventory turnovers 4.75 1140 570
service: total monetary turnovers 6.73 27 808
service: aggregate perf. data 0.84 19083 101
service: refresh index statistics 0.03 250449 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2022 01:39:53 to 13-06-2022 01:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4173
TEST_TIME 2 4224
TEST_TIME 3 4489
TEST_TIME 4 4177
TEST_TIME 5 5252
TEST_TIME 6 5454
TEST_TIME 7 5635
TEST_TIME 8 5661
TEST_TIME 9 6034
TEST_TIME 10 5989
TEST_TIME 11 6412
TEST_TIME 12 6553
TEST_TIME 13 6585
TEST_TIME 14 7106
TEST_TIME 15 6995
TEST_TIME 16 7517
TEST_TIME 17 7832
TEST_TIME 18 8340
TEST_TIME 19 8539
TEST_TIME 20 8439
TEST_TIME 21 8465
TEST_TIME 22 8755
TEST_TIME 23 8985
TEST_TIME 24 8917
TEST_TIME 25 9022
TEST_TIME 26 9401
TEST_TIME 27 9480
TEST_TIME 28 9620
TEST_TIME 29 9619
TEST_TIME 30 9597
TEST_TIME 31 9980
TEST_TIME 32 10364
TEST_TIME 33 10516
TEST_TIME 34 10556
TEST_TIME 35 10536
TEST_TIME 36 10576
TEST_TIME 37 10421
TEST_TIME 38 10318
TEST_TIME 39 10235
TEST_TIME 40 10395
TEST_TIME 41 10332
TEST_TIME 42 10422
TEST_TIME 43 10485
TEST_TIME 44 10312
TEST_TIME 45 10175
TEST_TIME 46 10166
TEST_TIME 47 10036
TEST_TIME 48 9947
TEST_TIME 49 9990
TEST_TIME 50 9895
TEST_TIME 51 9814
TEST_TIME 52 9960
TEST_TIME 53 10111
TEST_TIME 54 10126
TEST_TIME 55 10033
TEST_TIME 56 9961
TEST_TIME 57 9903
TEST_TIME 58 9796
TEST_TIME 59 9798
TEST_TIME 60 9815
TEST_TIME 61 9716
TEST_TIME 62 9682
TEST_TIME 63 9700
TEST_TIME 64 9774
TEST_TIME 65 9709
TEST_TIME 66 9759
TEST_TIME 67 9743
TEST_TIME 68 9819
TEST_TIME 69 9868
TEST_TIME 70 9851
TEST_TIME 71 9856
TEST_TIME 72 9809
TEST_TIME 73 9862
TEST_TIME 74 9838
TEST_TIME 75 9894
TEST_TIME 76 9932
TEST_TIME 77 9848
TEST_TIME 78 9810
TEST_TIME 79 9757
TEST_TIME 80 9753
TEST_TIME 81 9701
TEST_TIME 82 9636
TEST_TIME 83 9608
TEST_TIME 84 9584
TEST_TIME 85 9500
TEST_TIME 86 9509
TEST_TIME 87 9453
TEST_TIME 88 9438
TEST_TIME 89 9457
TEST_TIME 90 9488
TEST_TIME 91 9518
TEST_TIME 92 9506
TEST_TIME 93 9491
TEST_TIME 94 9584
TEST_TIME 95 9572
TEST_TIME 96 9605
TEST_TIME 97 9606
TEST_TIME 98 9634
TEST_TIME 99 9718
TEST_TIME 100 9688
TEST_TIME 101 9718
TEST_TIME 102 9830
TEST_TIME 103 9801
TEST_TIME 104 9730
TEST_TIME 105 9705
TEST_TIME 106 9686
TEST_TIME 107 9634
TEST_TIME 108 9605
TEST_TIME 109 9609
TEST_TIME 110 9595
TEST_TIME 111 9613
TEST_TIME 112 9624
TEST_TIME 113 9583
TEST_TIME 114 9541
TEST_TIME 115 9541
TEST_TIME 116 9531
TEST_TIME 117 9515
TEST_TIME 118 9519
TEST_TIME 119 9527
TEST_TIME 120 9464

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-06-2022 01:39:57 to 13-06-2022 01:40:00.

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 1572516 1572516 0 0.00 0 18684 6 0 0
doc_list_biud 1572512 1572512 0 0.00 0 60947 25 0 0
sp_add_invoice_to_stock 5269 5103 166 3.15 20 188405 18061 0 166
sp_cancel_adding_invoice 5867 5867 0 0.00 5 81864 783 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 7973 369 0 0
sp_cancel_customer_reserve 573912 573912 0 0.00 1 60949 52 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 6897 78 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 2 15288 163 0 0
sp_cancel_supplier_invoice 6491 6478 13 0.20 2 27068 779 0 13
sp_cancel_supplier_order 417 417 0 0.00 6 876012 92347 0 0
sp_cancel_write_off 10657 10657 0 0.00 1 10400 97 0 0
sp_client_order 21039 21039 0 0.00 3 22349 330 0 0
sp_customer_reserve 472764 472764 0 0.00 0 60808 178 0 0
sp_fill_shopping_cart 32183 32183 0 0.00 1 19155 40 0 0
sp_fill_shopping_cart_clo_res 472757 472757 0 0.00 0 12082 12 0 0
sp_get_clo_for_invoice 5095 5095 0 0.00 0 24136 247 0 0
sp_kill_cost_storno 17630 17630 0 0.00 0 17136 56 0 0
sp_kill_qstorno_ret_qs2qd 1165376 1165376 0 0.00 0 60938 28 0 0
sp_kill_qty_storno 614481 614481 0 0.00 0 60946 63 0 0
sp_lock_selected_doc 21158 21158 0 0.00 0 10043 5 0 0
sp_make_cost_storno 28721 28721 0 0.00 0 18681 63 0 0
sp_multiply_rows_for_pdistr 28721 28721 0 0.00 0 9072 35 0 0
sp_multiply_rows_for_qdistr 21038 21038 0 0.00 0 10512 127 0 0
sp_pay_from_customer 7306 7306 0 0.00 4 15267 131 0 0
sp_pay_to_supplier 6038 6038 0 0.00 4 18692 153 0 0
sp_payment_common 13341 13341 0 0.00 1 11528 30 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 7935 344 0 0
sp_qd_handle_on_invoice_upd_sts 10961 10961 0 0.00 0 44204 343 0 0
sp_qd_handle_on_reserve_upd_sts 10284 10284 0 0.00 0 14119 64 0 0
sp_reserve_write_off 10284 10284 0 0.00 6 22309 196 0 0
sp_supplier_invoice 5493 5493 0 0.00 7 78181 3514 0 0
sp_supplier_order 5660 5660 0 0.00 5 29481 1274 0 0
srv_aggregate_perf_data 101 101 0 0.00 2800 231156 19083 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1720 119012 42914 0 0
srv_find_qd_qs_mism 1086279 1086279 0 0.00 0 14568 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 62 82185 1141 0 0
srv_make_money_saldo 808 808 0 0.00 1 2021 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 204859 316324 250449 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5103 5103 0 0.00 0 9796 177 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 8130 136 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 5984 19 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2116 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 486 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1881 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1050 16 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 18112 1609 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 3098 13 0 0
t$perf-norm:sp_client_order 21039 21039 0 0.00 0 15660 24 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 6555 14 0 0
t$perf-norm:sp_pay_from_customer 7306 7306 0 0.00 0 6380 11 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 9908 13 0 0
t$perf-norm:sp_reserve_write_off 10284 10284 0 0.00 0 15847 12 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 7411 21 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 7235 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 535 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 101 2 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 7092 36 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 3177 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10518 10186 332 3.15 0 15238 21 0 332
v_all_customers 38072 38072 0 0.00 0 50 1 0 0
v_all_suppliers 30926 30926 0 0.00 0 28 1 0 0
v_all_wares 42074 42074 0 0.00 0 125 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 4838 17 0 0
v_cancel_client_order 3544 3544 0 0.00 0 5201 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2269 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1268 5 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 15244 35 0 0
v_cancel_supplier_invoice 772 746 26 3.36 0 5641 28 0 26
v_cancel_supplier_order 834 834 0 0.00 0 347 3 0 0
v_cancel_write_off 2002 2002 0 0.00 0 4245 16 0 0
v_max_id_clo_ord 5658 5658 0 0.00 0 56 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 1414 12 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 2989 2 0 0
v_max_non_paid_invoice 1718 1718 0 0.00 0 2680 4 0 0
v_max_non_paid_realizn 4046 4046 0 0.00 0 2079 4 0 0
v_min_id_clo_ord 5658 5658 0 0.00 0 14757 8 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 2043 5 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 7915 10 0 0
v_min_non_paid_invoice 6036 6036 0 0.00 0 8477 19 0 0
v_min_non_paid_realizn 7305 7305 0 0.00 0 11524 23 0 0
v_our_firm 10520 10520 0 0.00 0 15 1 0 0
v_reserve_write_off 20564 20564 0 0.00 0 15236 7 0 0
x_lock_depdocs_on_canc_invoice 11972 11972 0 0.00 0 11487 108 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 6273 344 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 1 29470 1187 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 4 72124 3372 0 0
x_make_qstorno_2100_3300 472764 472764 0 0.00 0 60806 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2022 01:40:01 to 13-06-2022 01:40:05.

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
23:08:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:27 30707 8192000000 9911493104 10225156096 987674816 10.759 100 86 172 0 1017771472 6390080 23706576
23:11:07 18258 8192000000 9960521920 10289016832 1045135712 11.314 100 88 176 0 1076512096 8631072 22745312
23:12:29 12169 8192000000 9992639920 10329038848 1076146320 11.611 100 82 164 0 1106729296 10027376 20555600
23:13:49 9206 8192000000 10040320528 10391506944 1130668880 12.128 100 79 158 0 1160303408 10347024 19287504
23:15:01 7094 8192000000 10085501968 10449465344 1175088576 12.544 100 73 145 1 1203219536 11304768 16826192
23:16:11 5556 8192000000 10132155616 10500022272 1214118944 12.907 100 82 162 1 1245820944 13675072 18026928
23:17:19 4595 8192000000 10150340944 10527694848 1236795040 13.117 100 74 145 1 1267199920 14590608 15814272
23:18:25 1905 8192000000 10188544912 10566225920 1273842016 13.457 100 89 175 4 1306610368 12888448 19879904
23:19:30 1733 8192000000 10216237984 10597666816 1293690080 13.638 100 95 190 1 1330290304 14883296 21716928
23:20:32 4665 8192000000 10201210992 10600603648 1304240336 13.734 100 67 134 1 1327013392 6529520 16243536
23:21:42 1447 8192000000 10214191952 10611355648 1306537520 13.755 100 73 146 2 1333037088 10372528 16127040
23:22:52 7888 8192000000 10200844592 10615099392 1312256688 13.807 100 20 40 1 1323130384 6661008 4212688
23:24:01 6337 8192000000 10203061680 10621984768 1320542736 13.882 100 22 44 1 1331481632 5792656 5146240
23:25:10 11606 8192000000 10208398608 10628419584 1313928880 13.822 99 16 31 1 1323758016 6344144 3484992
23:26:23 37732 8192000000 10252290880 10652844032 1333842816 14.002 100 68 136 3 1355474880 4741232 16890832
23:28:01 4519 8192000000 10226008640 10644873216 1332311984 13.988 100 33 66 2 1346453632 6510400 7631248
23:29:08 9497 8192000000 10208806128 10612617216 1260270736 13.332 95 29 56 0 1272233296 5186768 6775792
23:30:18 21318 8192000000 10190647504 10598047744 1282048272 13.532 99 47 95 0 1303962272 10959248 10954752
23:31:42 3834 8192000000 10181593920 10586562560 1272944768 13.449 99 41 80 3 1292888928 10522128 9422032
23:32:50 10470 8192000000 10156392512 10558865408 1249416144 13.233 99 27 55 0 1261025280 5532832 6076304
23:34:01 13991 8192000000 10177968160 10589089792 1277366944 13.489 99 27 54 0 1289495008 5183056 6945008
23:35:18 14601 8192000000 10193551568 10607702016 1301106496 13.705 100 35 70 1 1316557456 6670560 8780400
23:36:36 7646 8192000000 10197471568 10612207616 1309609920 13.783 100 36 72 0 1324071584 5995120 8466544
23:37:45 7490 8192000000 10213693888 10631577600 1321179696 13.887 100 28 56 2 1334034096 6315040 6539360
23:38:58 43748 8192000000 10257413024 10670166016 1352209520 14.167 100 47 93 2 1371337344 8637712 10490112
23:40:45 32769 8192000000 10264794784 10682429440 1347187424 14.122 99 38 74 2 1361718176 6700192 7830560
23:42:23 8585 8192000000 10292573008 10711908352 1379518160 14.412 100 52 104 1 1404117552 13261296 11338096
23:43:33 6977 8192000000 10307489888 10724048896 1387844592 14.487 100 59 114 1 1415198144 13882208 13471344
23:44:44 2055 8192000000 10311937280 10725130240 1387409536 14.483 100 65 129 1 1417334752 15606480 14318736
23:45:50 8494 8192000000 10319236224 10738429952 1401352704 14.607 100 48 96 0 1429203264 15857136 11993424
23:47:08 23217 8192000000 10323261760 10753245184 1407009104 14.657 100 44 88 3 1436060384 17998224 11053056
23:48:36 21567 8192000000 10329875856 10756431872 1398600944 14.583 99 47 94 1 1430150192 19975568 11573680
23:50:10 1811 8192000000 10342736576 10761752576 1411208048 14.695 100 74 144 1 1446942160 18285088 17449024
23:51:16 3043 8192000000 10334297680 10748518400 1400445536 14.599 100 77 152 2 1442902064 24040384 18416144
23:52:27 7726 8192000000 10333041408 10751569920 1397056144 14.569 99 73 144 2 1442636896 27211312 18369440
23:53:43 2201 8192000000 10318948960 10735927296 1374328928 14.366 99 78 156 0 1418885408 25305360 19251120
23:54:52 2911 8192000000 10296313712 10710573056 1362034032 14.256 100 70 140 1 1404237328 25091824 17111472
23:55:59 1718 8192000000 10311959952 10711785472 1360142096 14.239 100 85 170 0 1409642112 29368832 20131184
23:57:12 4149 8192000000 10319181760 10732486656 1378602736 14.404 100 77 155 1 1420615056 23172976 18839344
23:58:22 5462 8192000000 10328117200 10747027456 1385256784 14.464 100 56 114 0 1426326944 27053152 14017008
23:59:32 4050 8192000000 10322350864 10736058368 1379223104 14.410 100 67 133 0 1429591872 34221296 16147472
00:00:42 7896 8192000000 10303579056 10722422784 1364523408 14.278 100 41 81 0 1404224192 29086128 10614656
00:01:53 6241 8192000000 10319361008 10741260288 1366678656 14.297 99 42 84 1 1405187280 27698112 10810512
00:03:02 9703 8192000000 10326761600 10744958976 1370366256 14.330 100 56 112 1 1421644032 35992640 15285136
00:04:14 14654 8192000000 10312621504 10742820864 1379450864 14.412 100 26 52 0 1418836432 31905728 7479840
00:05:40 22550 8192000000 10316073856 10753703936 1384948832 14.461 100 22 44 0 1421265424 30323984 5992608
00:07:07 24921 8192000000 10315753424 10758242304 1380357984 14.420 100 27 54 1 1421880960 34409168 7113808
00:08:36 18620 8192000000 10330408576 10761973760 1382057168 14.435 100 31 62 0 1430166128 39687328 8421632
00:09:56 38767 8192000000 10305869792 10765627392 1355292400 14.195 98 14 28 1 1397654768 38520336 3842032
00:12:13 38328 8192000000 10289657680 10732453888 1381239280 14.428 100 25 50 1 1395827824 9260480 5328064
00:13:52 85354 8192000000 10288414080 10719854592 1332188000 13.987 96 20 41 0 1348337760 11876384 4273376
00:16:22 19592 8192000000 10301802368 10732597248 1378769968 14.406 100 51 101 1 1402009440 12037712 11201760
00:17:46 119012 8192000000 10313862864 10759589888 1373268000 14.356 99 16 32 1 1394946832 18216016 3462816
00:20:46 76316 8192000000 10307483584 10773868544 1376529728 14.386 99 10 20 1 1399510992 20887024 2094240
00:23:51 103135 8192000000 10302215312 10747961344 1368502272 14.314 98 28 56 1 1388982784 13610352 6870160
00:27:55 41819 8192000000 10321509712 10776358912 1409388912 14.679 100 11 22 0 1424786272 12877920 2519440
00:29:39 40428 8192000000 10318487936 10788958208 1382077824 14.435 99 42 84 0 1412738368 19849840 10810704
00:31:31 44811 8192000000 10297274720 10769526784 1379016240 14.408 99 21 42 0 1407752928 23818592 4918096
00:33:23 16985 8192000000 10288215312 10756280320 1387516336 14.484 100 5 10 0 1399083584 10532080 1035168
00:34:42 41741 8192000000 10281312960 10734800896 1352667520 14.171 98 8 16 0 1364420304 9663360 2089424
00:38:19 55142 8192000000 10281109040 10747899904 1367984640 14.309 99 15 30 0 1379923232 8837696 3100896
00:40:44 78937 8192000000 10260940944 10717835264 1294603744 13.646 94 8 15 2 1307258736 11210976 1444016
00:43:03 17190 8192000000 10274749632 10723123200 1331957568 13.985 97 30 60 1 1345068464 6529792 6581104
00:44:20 48783 8192000000 10276719232 10730147840 1331414768 13.980 97 22 44 0 1346832576 10151520 5266288
00:46:14 64727 8192000000 10277188656 10731859968 1335890688 14.020 97 15 32 0 1350684512 11361088 3432736
00:48:27 92875 8192000000 10279112288 10730811392 1295327184 13.653 95 17 34 0 1307978864 9051952 3599728
00:51:00 40016 8192000000 10278644896 10733391872 1338427264 14.043 98 28 56 1 1355467184 10693024 6346896
00:52:49 70898 8192000000 10280066736 10738135040 1333445584 13.998 97 20 38 1 1354445840 16153520 4846736
00:55:02 109902 8192000000 10261010608 10718883840 1335096816 14.013 98 27 54 1 1350216176 8512352 6607008
00:57:55 54336 8192000000 10281849536 10746044416 1372821248 14.352 100 20 38 2 1393840784 16474976 4544560
00:59:59 101246 8192000000 10266956096 10724773888 1328847696 13.957 97 6 12 0 1336535744 6640720 1047328
01:03:27 89566 8192000000 10271569792 10736865280 1353826400 14.182 99 20 40 0 1376291840 17894112 4571328
01:06:02 87011 8192000000 10253392864 10725330944 1334746288 14.010 98 12 24 0 1351293312 13130672 3416352
01:08:34 71300 8192000000 10277664656 10737688576 1354975840 14.192 99 16 32 2 1371721120 13107936 3637344
01:11:27 39280 8192000000 10300291152 10752991232 1367331632 14.303 98 28 56 0 1384127568 10554512 6241424
01:13:07 93418 8192000000 10305610720 10773270528 1396649248 14.565 100 10 20 0 1415810768 16863744 2297776
01:16:57 76925 8192000000 10307040784 10773897216 1354496816 14.188 97 18 36 0 1378357280 19706368 4154096
01:19:14 18770 8192000000 10321490496 10773340160 1392165920 14.525 100 40 80 0 1413569040 12326304 9076816
01:20:38 82962 8192000000 10304223872 10771124224 1375151696 14.373 98 16 32 1 1391889072 13133936 3603440
01:24:08 39473 8192000000 10299755696 10749939712 1334441312 14.007 96 27 52 3 1350980448 11557488 4981648
01:26:03 22668 8192000000 10287268256 10742800384 1356081104 14.202 98 27 54 0 1375647600 13506080 6060416
01:27:27 54332 8192000000 10276495072 10738323456 1346145616 14.113 98 12 24 0 1365061536 15934288 2981632
01:29:27 76255 8192000000 10243986560 10710609920 1312573872 13.809 96 16 32 1 1331570752 15584960 3411920
01:31:43 85650 8192000000 10257421232 10714181632 1321510400 13.890 97 20 38 1 1342151872 15893520 4747952
01:34:09 84630 8192000000 10258120032 10717982720 1296092336 13.660 95 12 24 0 1313494992 14331472 3071184
01:36:35 52934 8192000000 10271180192 10732101632 1349213888 14.140 99 17 33 0 1367497552 14217616 4066048

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-06-2022 01:40:07 to 13-06-2022 01:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          240468
MON$OLDEST_ACTIVE               243659
MON$OLDEST_SNAPSHOT             243659
MON$NEXT_TRANSACTION            243659
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                       2063520
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:477: 
:478: mscs-test-161.budzdorov.ru    Mon Jun 13 01:38:22 2022
:479:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:480: 
:481: 
:482: mscs-test-161.budzdorov.ru    Mon Jun 13 01:38:26 2022
:483:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:484: 
:485: 
:486: mscs-test-161.budzdorov.ru    Mon Jun 13 01:38:27 2022
:487:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:488: 
:489: 
:490: mscs-test-161.budzdorov.ru    Mon Jun 13 01:38:28 2022
:491:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:492: 
--- end of diff output ---

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

13.06.22 01:40:26. 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-06-13 01:40:28 No severe PSQL-related problems occured
13.06.22 01:40:30. End of report.