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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16579080 kB
MemAvailable 31590280 kB
Buffers 0 kB
Cached 15165872 kB
SwapCached 3204 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-26 22:38:15
DTS_END                         2022-04-26 22:38:15.3350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42008
TRN_ID                          249200
ATT_ID                          647
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: 9358.88 20608 1123066
customer order: creation 180.19 327 21623
customer order: refuse 15.38 374 1846
order to supplier: creation 48.35 1175 5802
order to supplier: removal 3.48 91845 418
invoice (draft): creation 50.19 3172 6023
invoice (draft): removal 51.09 725 6131
invoice accept: apply 44.76 18386 5371
invoice accept: cancel 46.01 757 5521
customer reserve: creation 4073.24 185 488789
customer reserve: removal 4540.79 55 544895
realization accept: apply 89.40 169 10728
realization accept: cancel 77.41 108 9289
payment from customer: creation 63.07 119 7568
payment from customer: removal 6.19 72 743
payment to supplier: creation 51.58 133 6189
payment to supplier: removal 3.75 94 450
service: total inventory turnovers 5.37 844 644
service: total monetary turnovers 7.69 31 923
service: aggregate perf. data 0.91 18259 109
service: refresh index statistics 0.03 275320 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-04-2022 22:41:07 to 26-04-2022 22:41:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4436
TEST_TIME 2 4174
TEST_TIME 3 4761
TEST_TIME 4 5185
TEST_TIME 5 5737
TEST_TIME 6 5592
TEST_TIME 7 6266
TEST_TIME 8 6519
TEST_TIME 9 7131
TEST_TIME 10 7230
TEST_TIME 11 7611
TEST_TIME 12 7630
TEST_TIME 13 7649
TEST_TIME 14 7650
TEST_TIME 15 7702
TEST_TIME 16 8097
TEST_TIME 17 8479
TEST_TIME 18 8697
TEST_TIME 19 8754
TEST_TIME 20 9007
TEST_TIME 21 9575
TEST_TIME 22 9660
TEST_TIME 23 9658
TEST_TIME 24 10065
TEST_TIME 25 9989
TEST_TIME 26 10696
TEST_TIME 27 11239
TEST_TIME 28 11358
TEST_TIME 29 11200
TEST_TIME 30 11181
TEST_TIME 31 11177
TEST_TIME 32 11003
TEST_TIME 33 10976
TEST_TIME 34 10999
TEST_TIME 35 10769
TEST_TIME 36 10623
TEST_TIME 37 10517
TEST_TIME 38 10378
TEST_TIME 39 10346
TEST_TIME 40 10235
TEST_TIME 41 10270
TEST_TIME 42 10412
TEST_TIME 43 10288
TEST_TIME 44 10172
TEST_TIME 45 10075
TEST_TIME 46 10136
TEST_TIME 47 9983
TEST_TIME 48 9885
TEST_TIME 49 9885
TEST_TIME 50 9814
TEST_TIME 51 9810
TEST_TIME 52 9653
TEST_TIME 53 9658
TEST_TIME 54 9862
TEST_TIME 55 9800
TEST_TIME 56 9778
TEST_TIME 57 9819
TEST_TIME 58 9854
TEST_TIME 59 9932
TEST_TIME 60 10052
TEST_TIME 61 9999
TEST_TIME 62 10018
TEST_TIME 63 10018
TEST_TIME 64 10032
TEST_TIME 65 10055
TEST_TIME 66 10076
TEST_TIME 67 10125
TEST_TIME 68 10017
TEST_TIME 69 9920
TEST_TIME 70 9907
TEST_TIME 71 9832
TEST_TIME 72 9828
TEST_TIME 73 9817
TEST_TIME 74 9768
TEST_TIME 75 9750
TEST_TIME 76 9687
TEST_TIME 77 9606
TEST_TIME 78 9531
TEST_TIME 79 9487
TEST_TIME 80 9539
TEST_TIME 81 9564
TEST_TIME 82 9554
TEST_TIME 83 9544
TEST_TIME 84 9573
TEST_TIME 85 9562
TEST_TIME 86 9579
TEST_TIME 87 9617
TEST_TIME 88 9664
TEST_TIME 89 9634
TEST_TIME 90 9699
TEST_TIME 91 9702
TEST_TIME 92 9720
TEST_TIME 93 9768
TEST_TIME 94 9703
TEST_TIME 95 9640
TEST_TIME 96 9639
TEST_TIME 97 9586
TEST_TIME 98 9537
TEST_TIME 99 9490
TEST_TIME 100 9477
TEST_TIME 101 9400
TEST_TIME 102 9447
TEST_TIME 103 9431
TEST_TIME 104 9479
TEST_TIME 105 9461
TEST_TIME 106 9490
TEST_TIME 107 9463
TEST_TIME 108 9433
TEST_TIME 109 9505
TEST_TIME 110 9469
TEST_TIME 111 9450
TEST_TIME 112 9475
TEST_TIME 113 9553
TEST_TIME 114 9562
TEST_TIME 115 9574
TEST_TIME 116 9551
TEST_TIME 117 9502
TEST_TIME 118 9477
TEST_TIME 119 9437
TEST_TIME 120 9366

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-04-2022 22:41:11 to 26-04-2022 22:41:14.

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 1574627 1574627 0 0.00 0 14945 6 0 0
doc_list_biud 1574627 1574627 0 0.00 0 37042 25 0 0
sp_add_invoice_to_stock 5434 5371 63 1.15 27 164050 18386 0 63
sp_cancel_adding_invoice 5521 5521 0 0.00 4 41144 758 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 22767 374 0 0
sp_cancel_customer_reserve 544895 544895 0 0.00 1 19680 56 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 5516 72 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 7083 95 0 0
sp_cancel_supplier_invoice 6135 6131 4 0.06 1 15198 725 0 4
sp_cancel_supplier_order 418 418 0 0.00 9 930304 91845 0 0
sp_cancel_write_off 9289 9289 0 0.00 1 10492 109 0 0
sp_client_order 21623 21623 0 0.00 3 30835 327 0 0
sp_customer_reserve 488789 488789 0 0.00 0 112538 186 0 0
sp_fill_shopping_cart 33430 33430 0 0.00 1 34874 41 0 0
sp_fill_shopping_cart_clo_res 488783 488783 0 0.00 0 14277 11 0 0
sp_get_clo_for_invoice 5363 5363 0 0.00 0 20450 250 0 0
sp_kill_cost_storno 16003 16003 0 0.00 0 13961 54 0 0
sp_kill_qstorno_ret_qs2qd 1105628 1105628 0 0.00 0 19642 30 0 0
sp_kill_qty_storno 584191 584191 0 0.00 0 37041 65 0 0
sp_lock_selected_doc 22224 22224 0 0.00 0 8305 5 0 0
sp_make_cost_storno 29848 29848 0 0.00 0 13056 52 0 0
sp_multiply_rows_for_pdistr 29848 29848 0 0.00 0 10582 29 0 0
sp_multiply_rows_for_qdistr 21623 21623 0 0.00 0 27177 154 0 0
sp_pay_from_customer 7568 7568 0 0.00 4 19046 119 0 0
sp_pay_to_supplier 6189 6189 0 0.00 5 20141 134 0 0
sp_payment_common 13757 13757 0 0.00 1 10703 37 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 22761 345 0 0
sp_qd_handle_on_invoice_upd_sts 10884 10884 0 0.00 0 37040 307 0 0
sp_qd_handle_on_reserve_upd_sts 10728 10728 0 0.00 0 15325 55 0 0
sp_reserve_write_off 10728 10728 0 0.00 6 19892 170 0 0
sp_supplier_invoice 6023 6023 0 0.00 7 106799 3172 0 0
sp_supplier_order 5802 5802 0 0.00 6 39672 1176 0 0
srv_aggregate_perf_data 109 109 0 0.00 2850 125461 18260 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1378 138501 46022 0 0
srv_find_qd_qs_mism 1071945 1071945 0 0.00 0 12766 6 0 0
srv_make_invnt_saldo 644 644 0 0.00 29 47407 844 0 0
srv_make_money_saldo 923 923 0 0.00 1 3671 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 192645 482024 275321 0 0
t$perf-abend:sp_add_invoice_to_stock 63 63 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5371 5371 0 0.00 0 15568 183 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 10454 120 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 7855 22 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 7416 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 5499 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 3762 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 4872 31 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 35927 1513 0 0
t$perf-norm:sp_cancel_write_off 1117 1117 0 0.00 0 4591 9 0 0
t$perf-norm:sp_client_order 21623 21623 0 0.00 0 10072 21 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 3040 7 0 0
t$perf-norm:sp_pay_from_customer 7568 7568 0 0.00 0 7013 11 0 0
t$perf-norm:sp_pay_to_supplier 6189 6189 0 0.00 0 9894 12 0 0
t$perf-norm:sp_reserve_write_off 10728 10728 0 0.00 0 8647 14 0 0
t$perf-norm:sp_supplier_invoice 6023 6023 0 0.00 0 7435 25 0 0
t$perf-norm:sp_supplier_order 5802 5802 0 0.00 0 6121 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 348 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 34 1 0 0
t$perf-norm:srv_make_invnt_saldo 644 644 0 0.00 0 2889 25 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 2645 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10850 10724 126 1.16 0 6045 17 0 126
v_all_customers 39146 39146 0 0.00 0 124 1 0 0
v_all_suppliers 32154 32154 0 0.00 0 42 1 0 0
v_all_wares 43246 43246 0 0.00 0 140 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 615 6 0 0
v_cancel_client_order 3692 3692 0 0.00 0 7075 8 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 2742 6 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 481 3 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 7076 13 0 0
v_cancel_supplier_invoice 850 842 8 0.94 0 1030 13 0 8
v_cancel_supplier_order 836 836 0 0.00 0 650 4 0 0
v_cancel_write_off 2234 2234 0 0.00 0 836 11 0 0
v_max_id_clo_ord 5802 5802 0 0.00 0 506 1 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 2531 17 0 0
v_max_id_ord_sup 6003 6003 0 0.00 0 4632 4 0 0
v_max_non_paid_invoice 1917 1917 0 0.00 0 1824 4 0 0
v_max_non_paid_realizn 4220 4220 0 0.00 0 2040 4 0 0
v_min_id_clo_ord 5802 5802 0 0.00 0 4671 9 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 588 3 0 0
v_min_id_ord_sup 6003 6003 0 0.00 0 20506 7 0 0
v_min_non_paid_invoice 6189 6189 0 0.00 0 7363 20 0 0
v_min_non_paid_realizn 7568 7568 0 0.00 0 10695 35 0 0
v_our_firm 10796 10796 0 0.00 0 25 1 0 0
v_reserve_write_off 21456 21456 0 0.00 0 3497 5 0 0
x_lock_depdocs_on_canc_invoice 11231 11231 0 0.00 0 18209 127 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8876 369 0 0
x_make_qstorno_1000_1200 5802 5802 0 0.00 1 39662 1085 0 0
x_make_qstorno_1200_2000 6023 6023 0 0.00 3 106509 3030 0 0
x_make_qstorno_2100_3300 488789 488789 0 0.00 0 112536 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 22:41:15 to 26-04-2022 22:41:19.

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
20:08:18 5 8192000000 8800784736 8812793856 6651376 0.081 3 1 2 0 6752304 58144 42784
20:09:19 15671 8192000000 9884626336 10191884288 966800176 10.555 100 85 171 0 995600032 5613120 23186736
20:10:40 12216 8192000000 9929702912 10248011776 1014661552 11.020 100 88 176 0 1045820288 7751456 23407280
20:12:07 14221 8192000000 9973689344 10303504384 1055911456 11.417 100 78 156 0 1084628160 9149312 19567392
20:13:31 13382 8192000000 10025049248 10371842048 1115436352 11.984 100 82 164 0 1145538784 10346416 19756016
20:14:48 6225 8192000000 10080982464 10441629696 1168910608 12.487 100 81 162 0 1200435584 12505008 19019968
20:15:58 5677 8192000000 10131289408 10496028672 1212937248 12.896 100 83 166 1 1244259952 12412688 18910016
20:17:05 2314 8192000000 10160067696 10530336768 1243477584 13.178 100 93 187 1 1278797840 15099872 20220384
20:18:09 2348 8192000000 10190104880 10566533120 1276911408 13.485 100 85 169 1 1307143136 11617984 18613744
20:19:12 1557 8192000000 10211953664 10593542144 1298724352 13.684 100 90 182 2 1331127264 11569536 20833376
20:20:15 10799 8192000000 10201333968 10601402368 1305200784 13.743 100 33 66 0 1318932064 5853360 7877920
20:21:26 8019 8192000000 10198408672 10617384960 1320384256 13.880 100 14 27 1 1328982656 4925008 3673392
20:22:35 52623 8192000000 10225393904 10638581760 1325512480 13.927 100 42 82 1 1342605280 7818016 9274784
20:24:39 7149 8192000000 10223108560 10644877312 1333085840 13.995 100 28 56 0 1345578160 5954576 6537744
20:25:47 8356 8192000000 10222790496 10642874368 1326819184 13.938 99 19 38 1 1335770528 5364032 3587312
20:26:57 63408 8192000000 10225141568 10633601024 1284620304 13.555 96 15 29 2 1292825840 4269536 3936000
20:29:03 9580 8192000000 10204258576 10617073664 1311163648 13.797 100 29 59 3 1323906176 5614512 7128016
20:30:17 9478 8192000000 10142649072 10542850048 1214520448 12.911 97 18 36 1 1224365488 6020896 3824144
20:31:30 13411 8192000000 10138262336 10536140800 1202504736 12.800 95 14 28 2 1210897040 4944928 3447376
20:32:45 37585 8192000000 10139894624 10545348608 1208946512 12.859 95 21 42 0 1219267408 5258720 5062176
20:34:24 9937 8192000000 10179286576 10589024256 1286667200 13.574 100 30 60 1 1299274176 5674320 6932656
20:35:37 7536 8192000000 10192513472 10602504192 1297802080 13.675 100 24 48 0 1309456368 5188528 6465760
20:36:47 5827 8192000000 10195777712 10610900992 1305671616 13.747 100 16 31 2 1315867232 5660400 4535216
20:37:54 89747 8192000000 10232183712 10643890176 1316398992 13.844 100 41 82 1 1331747088 5904240 9443856
20:40:29 24632 8192000000 10254645296 10664419328 1344597472 14.099 100 59 118 1 1371521120 13772112 13151536
20:42:06 15977 8192000000 10269733968 10683691008 1359579680 14.234 100 37 73 0 1380568896 12244384 8744832
20:43:32 6991 8192000000 10275675376 10697527296 1368587632 14.314 100 35 69 0 1388518592 11563824 8367136
20:44:42 14972 8192000000 10298443408 10718904320 1379697104 14.414 100 59 118 3 1411507920 18044384 13766432
20:46:01 1376 8192000000 10316639888 10734166016 1390710336 14.512 100 66 129 3 1425783936 19437232 15636368
20:47:08 1600 8192000000 10336193120 10748096512 1395771712 14.557 100 88 172 2 1439838928 23761600 20305616
20:48:14 7142 8192000000 10311571680 10730491904 1371284208 14.339 98 56 111 0 1407920704 23514112 13122384
20:49:28 4420 8192000000 10344887824 10762706944 1405011760 14.640 100 68 136 1 1450230064 29523488 15694816
20:50:36 2809 8192000000 10343593296 10748862464 1392757808 14.530 100 85 168 1 1443100224 30303184 20039232
20:51:43 1814 8192000000 10316682096 10723610624 1373016496 14.354 100 76 152 0 1422957024 30500960 19439568
20:52:50 4540 8192000000 10332065824 10740740096 1380043520 14.417 100 74 148 0 1436822272 38388592 18390160
20:54:02 3515 8192000000 10341130480 10747699200 1377584192 14.395 100 71 142 1 1439391072 43722544 18084336
20:55:13 1594 8192000000 10302792976 10708348928 1345249072 14.105 99 69 138 1 1405914320 42016304 18648944
20:56:20 2947 8192000000 10337411824 10737168384 1366705168 14.298 100 90 176 2 1434360928 46017744 21638016
20:57:25 3512 8192000000 10312080272 10724552704 1362850688 14.263 100 52 105 0 1416440288 39325168 14264432
20:58:39 6618 8192000000 10349363216 10768457728 1397408624 14.572 100 64 128 1 1460267008 46368128 16490256
21:00:03 18620 8192000000 10346849616 10779590656 1402803920 14.620 100 33 67 1 1454545984 42881056 8861008
21:01:26 18154 8192000000 10329846928 10759528448 1348377408 14.133 97 31 62 1 1397964336 41222912 8364016
21:02:46 24817 8192000000 10312467728 10759626752 1368638816 14.315 99 23 46 2 1419473264 44408768 6425680
21:04:13 13370 8192000000 10312173360 10750070784 1360439952 14.241 100 30 59 0 1415205200 46375920 8389328
21:05:31 50468 8192000000 10293060976 10733047808 1370056640 14.328 100 24 49 2 1400652736 24150416 6445680
21:07:23 35089 8192000000 10288049904 10723213312 1361693184 14.253 99 34 68 0 1377049104 7794672 7561248
21:09:04 60201 8192000000 10283781744 10728816640 1333332880 13.997 96 11 22 0 1345206320 9917696 1955744
21:11:04 52096 8192000000 10307645488 10758635520 1377600624 14.395 99 14 28 0 1394815792 14381696 2833472
21:12:58 97475 8192000000 10309038576 10752466944 1378930912 14.407 99 25 48 0 1396739696 12850256 4958528
21:15:43 85897 8192000000 10312781376 10756083712 1379470928 14.412 99 32 64 1 1397657200 10706800 7479472
21:18:14 51941 8192000000 10313283392 10758914048 1372252928 14.347 98 55 109 0 1398785152 14114000 12418224
21:20:24 30060 8192000000 10295495568 10765893632 1395444304 14.554 100 13 26 1 1406252960 7976592 2832064
21:22:17 72838 8192000000 10294128512 10760437760 1400790400 14.602 100 10 20 0 1414044048 10840560 2413088
21:24:32 63943 8192000000 10287409632 10751737856 1357223808 14.212 97 8 16 0 1366956544 7745024 1987712
21:26:36 83461 8192000000 10281613760 10753884160 1349264752 14.141 97 18 36 0 1370632608 16745152 4622704
21:29:00 44027 8192000000 10308759952 10779598848 1401802560 14.611 100 35 70 0 1421954096 11663472 8488064
21:31:46 56297 8192000000 10291472736 10777772032 1395656464 14.556 100 13 26 0 1416813616 17794272 3362880
21:33:43 33478 8192000000 10304933680 10752258048 1372507936 14.350 99 36 72 0 1391117456 10719920 7889600
21:35:20 53446 8192000000 10296188928 10759069696 1348878704 14.137 97 17 33 1 1371428448 18534592 4015152
21:37:53 55295 8192000000 10263062224 10711080960 1314114256 13.823 96 15 30 0 1333880128 16126096 3639776
21:39:53 50143 8192000000 10274816720 10714673152 1331763728 13.983 98 37 74 2 1356140736 16448464 7928544
21:41:47 34949 8192000000 10287034528 10720010240 1338620096 14.045 98 26 52 2 1353771392 8811952 6339344
21:43:24 59989 8192000000 10284549824 10735546368 1352855152 14.173 99 19 38 0 1368370896 11541728 3974016
21:45:37 128815 8192000000 10272674864 10709725184 1326703824 13.937 97 18 36 0 1341229728 10757840 3768064
21:48:57 54802 8192000000 10257517616 10701533184 1341306480 14.069 99 27 54 1 1355854656 8335696 6212480
21:50:53 64406 8192000000 10254415952 10717921280 1351010400 14.157 99 10 20 1 1362411776 9122752 2278624
21:52:58 60379 8192000000 10247666416 10706853888 1291713728 13.620 94 6 12 0 1301016272 7824704 1477840
21:55:56 70481 8192000000 10287657168 10738634752 1360708256 14.244 99 20 40 1 1382864208 17833872 4322080
21:58:09 63034 8192000000 10271118128 10754158592 1359050832 14.229 99 7 14 0 1380723968 20029040 1644096
22:00:12 48424 8192000000 10284403584 10738884608 1374332016 14.366 100 20 40 4 1387759984 8930720 4497248
22:02:07 63448 8192000000 10285300384 10739752960 1346334896 14.114 97 21 41 1 1365218304 14346864 4536544
22:04:11 63165 8192000000 10322614896 10766045184 1396378192 14.563 100 12 24 1 1413015712 13577792 3059728
22:06:15 89184 8192000000 10314427168 10768855040 1407385024 14.661 100 23 46 1 1426728688 13756720 5586944
22:08:44 138501 8192000000 10310618736 10769960960 1372477216 14.349 97 9 18 1 1392385456 17993584 1914656
22:12:07 97058 8192000000 10295110400 10756440064 1362596224 14.261 97 23 46 3 1374648240 7127936 4924080
22:14:51 32178 8192000000 10241322832 10699415552 1342969600 14.084 99 8 15 1 1352607872 7174688 2463584
22:16:24 95910 8192000000 10259818448 10715750400 1321478432 13.890 96 9 18 0 1333598560 9819888 2300240
22:19:15 55760 8192000000 10249815120 10706878464 1323466496 13.908 97 9 18 0 1343072752 17261824 2344432
22:21:11 35484 8192000000 10281075232 10717110272 1343405520 14.088 99 19 38 0 1353817088 6249488 4162080
22:22:48 58121 8192000000 10259759632 10713112576 1327156320 13.941 97 21 42 1 1343030896 10844640 5029936
22:25:31 34811 8192000000 10248173408 10691112960 1304991152 13.741 97 21 42 0 1317387184 7782288 4613744
22:27:10 62641 8192000000 10246485248 10710958080 1342691088 14.082 100 30 60 1 1364113856 14571120 6851648
22:29:13 61313 8192000000 10259416208 10699096064 1319619904 13.873 98 39 78 2 1341616912 13016016 8980992
22:31:19 74895 8192000000 10252746432 10710683648 1320251728 13.879 98 9 18 0 1336391840 14326448 1813664
22:33:35 56550 8192000000 10249270896 10720890880 1351124848 14.158 100 8 16 0 1370796800 17275008 2396944
22:35:47 52748 8192000000 10268029136 10723487744 1347362864 14.124 99 29 58 0 1364125648 10262400 6500384
22:37:41 42922 8192000000 9696450736 10068791296 824674592 9.146 61 8 16 0 835182224 8868784 1638848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-04-2022 22:41:21 to 26-04-2022 22:41:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 63 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-04-2022 22:41:37 to 26-04-2022 22:41:39.

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          249199
MON$OLDEST_ACTIVE               249385
MON$OLDEST_SNAPSHOT             249385
MON$NEXT_TRANSACTION            249385
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                       2043040
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.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Apr 26 22:39:04 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Apr 26 22:39:04 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Apr 26 22:39:06 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Apr 26 22:39:06 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Apr 26 22:39:07 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
--- end of diff output ---

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

26.04.22 22:41:40. 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-26 22:41:42 No severe PSQL-related problems occured
26.04.22 22:41:44. End of report.