Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2020 07:37
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16602184 kB
MemAvailable 31434820 kB
Buffers 0 kB
Cached 14938760 kB
SwapCached 3704 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-12-10 07:36:24
DTS_END                         2020-12-10 07:36:24.2290
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(68:17)
IP                              ::1/39870
TRN_ID                          235036
ATT_ID                          583
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: 9357.14 22681 1122857
customer order: creation 172.46 411 20695
customer order: refuse 14.79 333 1775
order to supplier: creation 45.68 1304 5481
order to supplier: removal 3.49 122878 419
invoice (draft): creation 45.39 3178 5447
invoice (draft): removal 52.95 875 6354
invoice accept: apply 41.05 17047 4926
invoice accept: cancel 48.46 853 5815
customer reserve: creation 3841.30 164 460956
customer reserve: removal 4797.80 71 575736
realization accept: apply 84.88 194 10185
realization accept: cancel 79.85 113 9582
payment from customer: creation 60.35 139 7242
payment from customer: removal 5.46 117 655
payment to supplier: creation 49.25 150 5910
payment to supplier: removal 3.45 102 414
service: total inventory turnovers 4.01 3192 481
service: total monetary turnovers 5.53 619 664
service: aggregate perf. data 0.96 15190 115
service: refresh index statistics 0.04 286691 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2020 07:37:41 to 10-12-2020 07:37:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4128
TEST_TIME 2 4697
TEST_TIME 3 5097
TEST_TIME 4 4776
TEST_TIME 5 5499
TEST_TIME 6 5802
TEST_TIME 7 6235
TEST_TIME 8 6288
TEST_TIME 9 6513
TEST_TIME 10 6550
TEST_TIME 11 6664
TEST_TIME 12 6988
TEST_TIME 13 7240
TEST_TIME 14 7233
TEST_TIME 15 8059
TEST_TIME 16 8303
TEST_TIME 17 8342
TEST_TIME 18 8418
TEST_TIME 19 9040
TEST_TIME 20 8902
TEST_TIME 21 8870
TEST_TIME 22 9253
TEST_TIME 23 9159
TEST_TIME 24 9511
TEST_TIME 25 10504
TEST_TIME 26 10782
TEST_TIME 27 11161
TEST_TIME 28 12350
TEST_TIME 29 12366
TEST_TIME 30 12129
TEST_TIME 31 12108
TEST_TIME 32 11734
TEST_TIME 33 11477
TEST_TIME 34 11326
TEST_TIME 35 11168
TEST_TIME 36 11269
TEST_TIME 37 11097
TEST_TIME 38 10903
TEST_TIME 39 10804
TEST_TIME 40 10688
TEST_TIME 41 10592
TEST_TIME 42 10595
TEST_TIME 43 10469
TEST_TIME 44 10350
TEST_TIME 45 10284
TEST_TIME 46 10306
TEST_TIME 47 10385
TEST_TIME 48 10367
TEST_TIME 49 10300
TEST_TIME 50 10444
TEST_TIME 51 10488
TEST_TIME 52 10414
TEST_TIME 53 10494
TEST_TIME 54 10483
TEST_TIME 55 10494
TEST_TIME 56 10516
TEST_TIME 57 10427
TEST_TIME 58 10267
TEST_TIME 59 10189
TEST_TIME 60 10162
TEST_TIME 61 10061
TEST_TIME 62 10052
TEST_TIME 63 9969
TEST_TIME 64 10012
TEST_TIME 65 10030
TEST_TIME 66 10003
TEST_TIME 67 9924
TEST_TIME 68 10028
TEST_TIME 69 10029
TEST_TIME 70 9962
TEST_TIME 71 9919
TEST_TIME 72 10127
TEST_TIME 73 10062
TEST_TIME 74 10088
TEST_TIME 75 10133
TEST_TIME 76 10077
TEST_TIME 77 9957
TEST_TIME 78 9885
TEST_TIME 79 9846
TEST_TIME 80 9801
TEST_TIME 81 9707
TEST_TIME 82 9745
TEST_TIME 83 9743
TEST_TIME 84 9731
TEST_TIME 85 9751
TEST_TIME 86 9749
TEST_TIME 87 9712
TEST_TIME 88 9734
TEST_TIME 89 9747
TEST_TIME 90 9798
TEST_TIME 91 9794
TEST_TIME 92 9822
TEST_TIME 93 9791
TEST_TIME 94 9723
TEST_TIME 95 9705
TEST_TIME 96 9690
TEST_TIME 97 9642
TEST_TIME 98 9664
TEST_TIME 99 9665
TEST_TIME 100 9638
TEST_TIME 101 9624
TEST_TIME 102 9612
TEST_TIME 103 9705
TEST_TIME 104 9701
TEST_TIME 105 9690
TEST_TIME 106 9687
TEST_TIME 107 9735
TEST_TIME 108 9718
TEST_TIME 109 9696
TEST_TIME 110 9661
TEST_TIME 111 9592
TEST_TIME 112 9583
TEST_TIME 113 9540
TEST_TIME 114 9482
TEST_TIME 115 9503
TEST_TIME 116 9468
TEST_TIME 117 9440
TEST_TIME 118 9427
TEST_TIME 119 9415
TEST_TIME 120 9363

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2020 07:37:45 to 10-12-2020 07:37:48.

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 1550088 1550088 0 0.00 0 15711 6 0 0
doc_list_biud 1550087 1550087 0 0.00 0 73734 33 0 0
sp_add_invoice_to_stock 5105 4926 179 3.50 44 151617 17047 0 179
sp_cancel_adding_invoice 5815 5815 0 0.00 5 67919 853 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 22832 334 0 0
sp_cancel_customer_reserve 575736 575736 0 0.00 1 73736 72 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 21465 118 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 6121 103 0 0
sp_cancel_supplier_invoice 6363 6354 9 0.14 2 21032 875 0 9
sp_cancel_supplier_order 419 419 0 0.00 8 1236145 122879 0 0
sp_cancel_write_off 9582 9582 0 0.00 1 9396 114 0 0
sp_client_order 20695 20695 0 0.00 4 28074 411 0 0
sp_customer_reserve 460956 460956 0 0.00 0 47752 164 0 0
sp_fill_shopping_cart 31620 31620 0 0.00 1 11558 34 0 0
sp_fill_shopping_cart_clo_res 460953 460953 0 0.00 0 22091 17 0 0
sp_get_clo_for_invoice 4920 4920 0 0.00 0 48393 381 0 0
sp_kill_cost_storno 16466 16466 0 0.00 0 6622 60 0 0
sp_kill_qstorno_ret_qs2qd 1167827 1167827 0 0.00 0 67596 38 0 0
sp_kill_qty_storno 614786 614786 0 0.00 0 73733 81 0 0
sp_lock_selected_doc 20828 20828 0 0.00 0 7478 5 0 0
sp_make_cost_storno 28257 28257 0 0.00 0 14653 65 0 0
sp_multiply_rows_for_pdistr 28256 28256 0 0.00 0 8808 32 0 0
sp_multiply_rows_for_qdistr 20695 20695 0 0.00 0 28059 227 0 0
sp_pay_from_customer 7242 7242 0 0.00 4 25393 139 0 0
sp_pay_to_supplier 5910 5910 0 0.00 5 21463 151 0 0
sp_payment_common 13151 13151 0 0.00 1 17115 30 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 10498 292 0 0
sp_qd_handle_on_invoice_upd_sts 10735 10735 0 0.00 0 30071 394 0 0
sp_qd_handle_on_reserve_upd_sts 10185 10185 0 0.00 0 14860 50 0 0
sp_reserve_write_off 10185 10185 0 0.00 7 25226 195 0 0
sp_supplier_invoice 5447 5447 0 0.00 6 83587 3179 0 0
sp_supplier_order 5481 5481 0 0.00 6 25977 1305 0 0
srv_aggregate_perf_data 115 115 0 0.00 2817 220663 15190 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1041 151079 41480 0 0
srv_find_qd_qs_mism 1075329 1075329 0 0.00 0 7835 5 0 0
srv_make_invnt_saldo 481 481 0 0.00 56 109438 3192 0 0
srv_make_money_saldo 664 664 0 0.00 1 18909 620 0 0
srv_recalc_idx_stat 5 5 0 0.00 132572 427146 286691 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 17365 155 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 3294 86 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 8776 14 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 7308 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 3414 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 756 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 2105 22 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 14362 1469 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 1802 6 0 0
t$perf-norm:sp_client_order 20695 20695 0 0.00 0 7302 16 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 1562 7 0 0
t$perf-norm:sp_pay_from_customer 7242 7242 0 0.00 0 2245 6 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 7288 13 0 0
t$perf-norm:sp_reserve_write_off 10185 10185 0 0.00 0 5744 10 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 10788 18 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 4656 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 307 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 81 3 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 2105 18 0 0
t$perf-norm:srv_make_money_saldo 664 664 0 0.00 0 765 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 10198 9840 358 3.51 0 19014 21 0 358
v_all_customers 38182 38182 0 0.00 0 20 1 0 0
v_all_suppliers 30796 30796 0 0.00 0 69 1 0 0
v_all_wares 41388 41388 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2293 9 0 0
v_cancel_client_order 3550 3550 0 0.00 0 22445 11 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 21455 22 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1966 7 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 6113 13 0 0
v_cancel_supplier_invoice 878 860 18 2.05 0 20441 37 0 18
v_cancel_supplier_order 838 838 0 0.00 0 3806 11 0 0
v_cancel_write_off 1988 1988 0 0.00 0 1747 14 0 0
v_max_id_clo_ord 5480 5480 0 0.00 0 2827 2 0 0
v_max_id_clo_res 940 940 0 0.00 0 3125 22 0 0
v_max_id_ord_sup 5443 5443 0 0.00 0 2989 4 0 0
v_max_non_paid_invoice 1434 1434 0 0.00 0 740 3 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 4298 5 0 0
v_min_id_clo_ord 5480 5480 0 0.00 0 3139 5 0 0
v_min_id_clo_res 940 940 0 0.00 0 3568 14 0 0
v_min_id_ord_sup 5443 5443 0 0.00 0 1578 3 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 17110 19 0 0
v_min_non_paid_realizn 7242 7242 0 0.00 0 10736 24 0 0
v_our_firm 10492 10492 0 0.00 0 17 1 0 0
v_reserve_write_off 20368 20368 0 0.00 0 25149 12 0 0
x_lock_depdocs_on_canc_invoice 11739 11739 0 0.00 0 19084 101 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12471 361 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 25947 1231 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 3 83563 3063 0 0
x_make_qstorno_2100_3300 460956 460956 0 0.00 0 47736 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2020 07:37:49 to 10-12-2020 07:37:53.

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
05:06:28 5 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
05:07:28 25565 8192000000 9726625840 10026713088 811172624 9.009 100 87 174 0 838983920 7611296 20200000
05:08:58 29377 8192000000 9770988352 10076659712 841119008 9.311 100 96 192 0 877601744 13957136 22525600
05:10:36 16427 8192000000 9811738352 10131259392 869810448 9.598 100 85 170 0 914425024 25349568 19265008
05:11:59 15540 8192000000 9894294032 10229501952 920613376 10.102 100 84 166 1 1006861152 68287200 17960576
05:13:16 11412 8192000000 9912534304 10259484672 957793872 10.467 100 81 160 1 1031434368 57117856 16522640
05:14:29 6093 8192000000 9970607680 10323730432 993595920 10.816 100 81 162 1 1077893280 68340928 15956432
05:15:37 3396 8192000000 10011854560 10372100096 1025903248 11.129 100 81 161 2 1120808640 77682560 17222832
05:16:43 2014 8192000000 10022778320 10391945216 1052441984 11.384 100 87 174 0 1134419920 63266912 18711024
05:17:47 1060 8192000000 10029125600 10397188096 1063779216 11.493 100 91 180 1 1136984960 54091728 19114016
05:18:49 4411 8192000000 10041591088 10441605120 1064037088 11.495 100 59 118 1 1157997136 82236880 11723168
05:19:56 4002 8192000000 10000831136 10395459584 1074790400 11.598 100 40 80 0 1120351136 36649584 8911152
05:21:02 4479 8192000000 10003096928 10396569600 1087910288 11.723 100 47 93 0 1120223872 22080592 10232992
05:22:07 37795 8192000000 10025511744 10410393600 1096234304 11.802 100 71 144 2 1127421184 15392160 15794720
05:23:46 4202 8192000000 10026898848 10427314176 1104116224 11.877 100 49 98 1 1136596608 21143344 11337040
05:24:52 5796 8192000000 10018117696 10431049728 1102753136 11.864 100 47 94 2 1135934528 23106784 10074608
05:25:59 6369 8192000000 10007021296 10404335616 1093124368 11.772 100 40 77 3 1117899904 15730448 9045088
05:27:06 50578 8192000000 9989496464 10402656256 1047454176 11.336 96 37 74 0 1077011824 21617872 7939776
05:28:57 8183 8192000000 9934790832 10331250688 1012971648 11.004 97 35 70 2 1044160144 23229136 7959360
05:30:08 4500 8192000000 9948918848 10324824064 1020467696 11.077 99 47 96 2 1041735792 10818272 10449824
05:31:17 6271 8192000000 9943127952 10322219008 1011898352 10.994 99 39 80 2 1036759936 15129984 9731600
05:32:25 33278 8192000000 9966746032 10371092480 1039250736 11.257 100 34 68 2 1064063216 17412656 7399824
05:34:05 6255 8192000000 9965989520 10350632960 1051583104 11.376 100 42 82 4 1076318768 15557344 9178320
05:35:12 8120 8192000000 9967862000 10380382208 1061243152 11.468 100 44 85 2 1093547408 22235152 10069104
05:36:23 27873 8192000000 9996109872 10391867392 1086389232 11.708 100 24 46 1 1107362624 15878976 5094416
05:37:54 26610 8192000000 10050751264 10452697088 1102086080 11.857 100 52 104 3 1173573344 60804576 10682688
05:39:25 9259 8192000000 10093461968 10497576960 1120048208 12.027 100 52 100 3 1205856288 74671168 11136912
05:40:39 19870 8192000000 10120257824 10525323264 1134292992 12.162 100 51 102 3 1233732096 88943984 10495120
05:42:01 6491 8192000000 10149660704 10555510784 1136263232 12.180 99 51 100 3 1247035248 99694928 11077088
05:43:11 5523 8192000000 10189614400 10595368960 1153824464 12.345 100 59 114 3 1291659168 125659520 12175184
05:44:20 3192 8192000000 10226511152 10637410304 1161248992 12.415 100 55 110 0 1339899136 165602400 13047744
05:45:38 10051 8192000000 10264993328 10680934400 1177962352 12.571 100 48 97 1 1368758496 180057472 10738672
05:46:53 5324 8192000000 10310566192 10729099264 1187099536 12.656 100 61 122 1 1411696736 209939856 14657344
05:48:03 2140 8192000000 10347318576 10760110080 1178702464 12.578 100 68 135 0 1453173808 258231952 16239392
05:49:10 2975 8192000000 10382147504 10790383616 1179555632 12.586 100 80 159 0 1485123392 287486208 18081552
05:50:19 5200 8192000000 10364161312 10778103808 1151474912 12.323 99 55 107 0 1453169728 289251920 12442896
05:51:28 1344 8192000000 10414587296 10814832640 1145561424 12.268 99 93 183 1 1513981856 346939280 21481152
05:52:35 1897 8192000000 10344163392 10750136320 1139774512 12.213 100 86 168 3 1440915792 281989344 19151936
05:53:41 2772 8192000000 10381144048 10787614720 1155901584 12.365 100 84 163 0 1475677472 300814784 18961104
05:54:50 1037 8192000000 10429298432 10839433216 1150652496 12.316 100 94 189 2 1522982560 350336848 21993216
05:55:55 13557 8192000000 10387606048 10804396032 1112970992 11.961 97 36 72 1 1446238880 323881376 9386512
05:57:19 18386 8192000000 10524230384 10955173888 1153492080 12.342 100 46 92 0 1612118848 446260368 12366400
05:58:42 7828 8192000000 10495385168 10945024000 1140691888 12.222 100 59 116 1 1634314896 479213248 14409760
06:00:02 9502 8192000000 10528855520 10979151872 1132723232 12.147 100 54 108 1 1637036928 490075408 14238288
06:01:36 29995 8192000000 10433577344 10956623872 1128277360 12.105 100 26 52 0 1532568544 397219136 7072048
06:03:07 24494 8192000000 10349405680 10838048768 1100678176 11.844 100 17 34 1 1443104928 337729360 4697392
06:04:35 47805 8192000000 10090452512 10614800384 1103226720 11.868 99 9 18 0 1190630240 85728496 1675024
06:06:23 39613 8192000000 10071822912 10504982528 1115162256 11.981 99 31 62 1 1161661312 39654000 6845056
06:08:06 94641 8192000000 10023272880 10441560064 1069483632 11.547 95 23 45 0 1103687680 29856480 4347568
06:10:42 25655 8192000000 10086854432 10527617024 1112174368 11.953 99 58 114 0 1178623184 53834992 12613824
06:12:39 40122 8192000000 10045736112 10522861568 1124978144 12.074 100 13 26 0 1147491744 19726928 2786672
06:14:20 47672 8192000000 10084447776 10537287680 1115807392 11.987 99 16 32 0 1148379904 29215952 3356560
06:16:17 52259 8192000000 10112963664 10576830464 1140934288 12.224 100 25 50 0 1208785952 62602336 5249328
06:18:13 35889 8192000000 10071817552 10593738752 1139326704 12.209 100 6 12 0 1179294128 38785632 1181792
06:19:52 43927 8192000000 10117065632 10570674176 1149851344 12.308 100 15 30 0 1209357104 56484304 3021456
06:22:11 50406 8192000000 10140499840 10655080448 1127150816 12.094 98 4 8 0 1207423664 79424464 848384
06:24:13 37233 8192000000 10131563632 10590863360 1116801200 11.997 97 41 82 0 1203404672 78005424 8598048
06:26:00 91933 8192000000 10126982768 10585710592 1142355152 12.238 99 18 36 0 1228345472 82009680 3980640
06:28:43 28652 8192000000 10120328608 10566574080 1142984608 12.244 99 32 64 1 1196382432 46883792 6514032
06:30:16 58254 8192000000 10137642352 10631069696 1160764176 12.410 100 22 44 0 1230003984 64746224 4493584
06:32:38 97758 8192000000 10118371680 10590826496 1156836000 12.374 100 25 50 0 1223662992 61888672 4938320
06:35:21 45915 8192000000 10075074384 10551664640 1132639456 12.146 99 21 40 1 1192125952 54906832 4579664
06:37:16 57965 8192000000 10130954032 10633195520 1150030800 12.310 100 7 14 1 1203091104 51840320 1219984
06:40:45 73738 8192000000 10093679840 10585337856 1126284432 12.086 98 19 38 2 1179901472 48900000 4717040
06:43:03 45988 8192000000 10120246800 10557849600 1126911680 12.092 98 21 42 2 1195763680 64221872 4630128
06:44:50 84748 8192000000 10085152592 10532753408 1073923600 11.590 94 22 44 2 1114278864 35564160 4791104
06:47:17 85288 8192000000 10060652016 10569236480 1075634272 11.606 95 15 30 0 1197939952 118750736 3554944
06:49:43 38698 8192000000 10075748128 10525839360 1062297456 11.478 96 30 59 1 1106556224 38294416 5964352
06:51:35 35204 8192000000 10043845104 10480193536 1048180144 11.343 95 23 46 0 1087904208 35213872 4510192
06:53:12 102200 8192000000 10085028896 10543509504 1103111600 11.867 100 20 40 1 1187867808 80577248 4178960
06:58:48 74816 8192000000 10094502912 10571399168 1075048560 11.600 97 18 36 0 1148192688 69143136 4000992
07:01:11 55832 8192000000 10044930528 10497056768 1078891952 11.637 98 16 32 0 1116744288 34305248 3547088
07:03:09 29450 8192000000 10093761440 10525990912 1098195552 11.821 99 27 54 1 1174263168 70275408 5792208
07:04:43 60380 8192000000 10095921904 10599174144 1096335440 11.803 99 21 42 0 1207039280 105880736 4823104
07:06:47 34367 8192000000 10070589040 10517831680 1073508896 11.586 96 25 50 0 1102908032 24669360 4729776
07:08:25 100671 8192000000 10066679936 10528681984 1120282064 12.030 100 13 26 1 1153701072 31034832 2384176
07:11:06 47228 8192000000 10075347280 10541449216 1133493072 12.154 100 11 22 0 1180614592 44816112 2305408
07:12:56 31628 8192000000 10080062208 10505592832 1130774704 12.129 99 29 58 0 1160010976 23927824 5308448
07:14:35 58032 8192000000 10082958896 10530283520 1140590496 12.221 100 10 20 0 1177454608 35052112 1812000
07:16:33 52205 8192000000 10102028144 10543763456 1141381680 12.229 99 33 65 3 1198061024 50367152 6312192
07:18:25 14477 8192000000 10116591056 10548113408 1156446704 12.370 100 50 101 3 1206493888 39802176 10245008
07:19:40 103426 8192000000 10099797312 10546974720 1119020560 12.018 97 15 30 1 1176027104 54304720 2701824
07:22:24 39421 8192000000 10080440336 10544910336 1139089568 12.207 99 9 16 2 1186988368 45942256 1956544
07:24:05 90455 8192000000 10122544704 10570031104 1129673424 12.118 98 8 16 2 1162421072 31165952 1581696
07:26:37 151078 8192000000 10086464112 10539560960 1144924608 12.262 99 18 36 0 1193735520 44406080 4404832
07:30:17 50340 8192000000 10133923440 10591051776 1142481040 12.239 98 5 10 0 1202152016 58759024 911952
07:32:08 51116 8192000000 10060834288 10535292928 1093610288 11.777 96 4 8 0 1144641392 50194048 837056
07:34:02 18302 8192000000 10096348304 10532057088 1137366464 12.191 100 31 63 1 1187219824 43103648 6749712
07:35:30 35980 8192000000 10089785056 10523103232 1082614688 11.672 96 22 43 5 1143228416 56015952 4597776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2020 07:37:55 to 10-12-2020 07:38:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2020 07:38:11 to 10-12-2020 07:38:13.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232238
MON$OLDEST_ACTIVE               237076
MON$OLDEST_SNAPSHOT             237076
MON$NEXT_TRANSACTION            237076
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-30 07:30:08.6030 GMT
MON$PAGES                       2117760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Dec 10 07:36:29 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Dec 10 07:36:31 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Dec 10 07:36:31 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Dec 10 07:36:31 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

10.12.20 07:38:14. There are 3 logs in /var/tmp/logs-oltp40 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
2020-12-10 07:38:17 No severe PSQL-related problems occured
10.12.20 07:38:18. End of report.